Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250517140735\orcl_w000_12872_bucket.trc Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options Windows NT Version V6.2 CPU : 32 - type 8664, 16 Physical Cores Process Affinity : 0x0x0000000000000000 Memory (Avail/Total): Ph:37359M/63366M, Ph+PgF:44562M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12872, image: ORACLE.EXE (W000) *** 2025-05-17 14:07:35.608 *** SESSION ID:(177.12143) 2025-05-17 14:07:35.608 *** 2025-05-17 14:07:35.608 Process diagnostic dump for ORACLE.EXE (W000), OS id=12872, pid: 22, proc_ser: 118, sid: 177, sess_ser: 12143 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.616254 sec, exc=0.616254 sec, total=0.616254 sec wait times: max=5.000000 sec, heur=50.686810 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.000287 sec, exc=5.000287 sec, total=5.000287 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.013022 sec, exc=5.013022 sec, total=5.013022 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.008334 sec, exc=5.008334 sec, total=5.008334 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.014744 sec, exc=5.014744 sec, total=5.014744 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=5.007686 sec, exc=5.007686 sec, total=5.007686 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.001702 sec, exc=5.001702 sec, total=5.001702 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=5.001468 sec, exc=5.001468 sec, total=5.001468 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=5.013455 sec, exc=5.013455 sec, total=5.013455 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=5.005002 sec, exc=5.005002 sec, total=5.005002 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.003947 sec of elapsed time Sampled Session History of session 177 serial 12143 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [51 samples, 14:06:44 - 14:07:35] idle wait at each sample [session created at: 14:06:44] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-17 14:07:35.608 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12872, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-17 11:36:24.312098 :98443C94:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 11:36:24.312588 :98443C95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 11:36:24.312632 :98443C9C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 11:46:25.248426 :984502B3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 11:46:25.596993 :98450308:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 11:46:25.592 2025-05-17 11:46:25.596995 :98450309:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11680 pso_num=22 pso_serial#=105 2025-05-17 11:46:25.597132 :9845030A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11680 osp_idx=0 osp_ver=202320 osp_pg=0 (spawn #191077) 2025-05-17 11:46:25.597133 :9845030B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 11:46:25.597133 :9845030C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=458195469 2025-05-17 11:46:25.597134 :9845030D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 11:46:25.597134 :9845030E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 11:46:25.597134 :9845030F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 11:46:25.597135 :98450310:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 11:46:25.597135 :98450311:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 11:46:25.597620 :98450312:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 11:46:25.597665 :9845031A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 11:56:26.427969 :9845C8CD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 11:56:26.901394 :9845C939:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 11:56:26.896 2025-05-17 11:56:26.901396 :9845C93A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12400 pso_num=22 pso_serial#=106 2025-05-17 11:56:26.901533 :9845C93B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12400 osp_idx=0 osp_ver=202321 osp_pg=0 (spawn #191100) 2025-05-17 11:56:26.901533 :9845C93C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 11:56:26.901534 :9845C93D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=458796766 2025-05-17 11:56:26.901534 :9845C93E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 11:56:26.901535 :9845C93F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 11:56:26.901535 :9845C940:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 11:56:26.901535 :9845C941:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 11:56:26.901536 :9845C942:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 11:56:26.902082 :9845C943:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 11:56:26.902125 :9845C949:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 12:06:27.884583 :984699DD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 12:06:28.218502 :98469A4A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 12:06:28.214 2025-05-17 12:06:28.218504 :98469A4B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11128 pso_num=22 pso_serial#=107 2025-05-17 12:06:28.218642 :98469A4C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11128 osp_idx=0 osp_ver=202322 osp_pg=0 (spawn #191126) 2025-05-17 12:06:28.218643 :98469A4D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 12:06:28.218643 :98469A4E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=459398094 2025-05-17 12:06:28.218643 :98469A4F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 12:06:28.218644 :98469A50:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 12:06:28.218644 :98469A51:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 12:06:28.218644 :98469A52:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 12:06:28.218645 :98469A53:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 12:06:28.219133 :98469A54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 12:06:28.219181 :98469A5E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 12:16:29.100692 :98475F49:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 12:16:29.589742 :98475F85:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 12:16:29.585 2025-05-17 12:16:29.589744 :98475F86:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12232 pso_num=22 pso_serial#=108 2025-05-17 12:16:29.589889 :98475F87:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12232 osp_idx=0 osp_ver=202323 osp_pg=0 (spawn #191148) 2025-05-17 12:16:29.589890 :98475F88:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 12:16:29.589890 :98475F89:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=13 time=459999454 2025-05-17 12:16:29.589890 :98475F8A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 12:16:29.589891 :98475F8B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 12:16:29.589892 :98475F8C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 12:16:29.589892 :98475F8D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 12:16:29.589892 :98475F8E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 12:16:29.590389 :98475F8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 12:16:29.590435 :98475F97:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 12:26:30.475202 :98482701:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 12:26:30.839353 :98482748:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 12:26:30.835 2025-05-17 12:26:30.839356 :98482749:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14164 pso_num=22 pso_serial#=109 2025-05-17 12:26:30.839502 :9848274A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14164 osp_idx=0 osp_ver=202324 osp_pg=0 (spawn #191171) 2025-05-17 12:26:30.839503 :9848274B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 12:26:30.839503 :9848274C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=14 time=460600704 2025-05-17 12:26:30.839504 :9848274D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 12:26:30.839504 :9848274E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 12:26:30.839504 :9848274F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 12:26:30.839505 :98482750:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 12:26:30.839505 :98482751:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 12:26:30.840012 :98482752:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 12:26:30.840056 :9848275A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 12:36:31.751076 :9848EE0B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 12:36:32.333943 :9848EE44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 12:36:32.329 2025-05-17 12:36:32.333945 :9848EE45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14000 pso_num=22 pso_serial#=110 2025-05-17 12:36:32.334082 :9848EE46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14000 osp_idx=0 osp_ver=202325 osp_pg=0 (spawn #191194) 2025-05-17 12:36:32.334082 :9848EE47:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 12:36:32.334083 :9848EE48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=15 time=461202204 2025-05-17 12:36:32.334083 :9848EE49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 12:36:32.334083 :9848EE4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 12:36:32.334084 :9848EE4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 12:36:32.334084 :9848EE4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 12:36:32.334084 :9848EE4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 12:36:32.334584 :9848EE4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 12:36:32.334633 :9848EE56:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 12:46:33.208401 :9849B534:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 12:46:33.738777 :9849B573:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 12:46:33.734 2025-05-17 12:46:33.738779 :9849B574:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12348 pso_num=22 pso_serial#=111 2025-05-17 12:46:33.738917 :9849B575:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12348 osp_idx=0 osp_ver=202326 osp_pg=0 (spawn #191216) 2025-05-17 12:46:33.738917 :9849B576:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 12:46:33.738917 :9849B577:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=16 time=461803610 2025-05-17 12:46:33.738918 :9849B578:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 12:46:33.738918 :9849B579:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 12:46:33.738919 :9849B57A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 12:46:33.738919 :9849B57B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 12:46:33.738919 :9849B57C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 12:46:33.739427 :9849B57D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 12:46:33.739479 :9849B584:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 12:56:34.711465 :984A7B06:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 12:56:35.263067 :984A7B50:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 12:56:35.258 2025-05-17 12:56:35.263069 :984A7B51:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6976 pso_num=22 pso_serial#=112 2025-05-17 12:56:35.263208 :984A7B52:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6976 osp_idx=0 osp_ver=202327 osp_pg=0 (spawn #191239) 2025-05-17 12:56:35.263208 :984A7B53:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 12:56:35.263209 :984A7B54:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=17 time=462405125 2025-05-17 12:56:35.263209 :984A7B55:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 12:56:35.263210 :984A7B56:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 12:56:35.263210 :984A7B57:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 12:56:35.263210 :984A7B58:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 12:56:35.263211 :984A7B59:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 12:56:35.263712 :984A7B5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 12:56:35.263757 :984A7B62:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 13:06:36.269107 :984B4CA3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 13:06:36.742562 :984B4CDF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 13:06:36.737 2025-05-17 13:06:36.742564 :984B4CE0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14324 pso_num=22 pso_serial#=113 2025-05-17 13:06:36.742705 :984B4CE1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14324 osp_idx=0 osp_ver=202328 osp_pg=0 (spawn #191265) 2025-05-17 13:06:36.742706 :984B4CE2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 13:06:36.742706 :984B4CE3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=18 time=463006610 2025-05-17 13:06:36.742707 :984B4CE4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 13:06:36.742707 :984B4CE5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 13:06:36.742708 :984B4CE6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 13:06:36.742708 :984B4CE7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 13:06:36.742709 :984B4CE8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 13:06:36.743206 :984B4CE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 13:06:36.743252 :984B4CEF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 13:16:37.543948 :984C117A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 13:16:38.095596 :984C11B1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 13:16:38.090 2025-05-17 13:16:38.095598 :984C11B2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13236 pso_num=22 pso_serial#=114 2025-05-17 13:16:38.095734 :984C11B3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13236 osp_idx=0 osp_ver=202329 osp_pg=0 (spawn #191287) 2025-05-17 13:16:38.095735 :984C11B4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 13:16:38.095736 :984C11B5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=19 time=463607969 2025-05-17 13:16:38.095736 :984C11B6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 13:16:38.095737 :984C11B7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 13:16:38.095737 :984C11B8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 13:16:38.095737 :984C11B9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 13:16:38.095738 :984C11BA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 13:16:38.096230 :984C11BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 13:16:38.096278 :984C11C3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 13:20:20.440236 :984C5AFA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 13:26:39.492020 :984CD8A4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-17 13:36:40.439965 :984D9DED:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 13:36:40.960272 :984D9E2B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 13:36:40.955 2025-05-17 13:36:40.960274 :984D9E2C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10888 pso_num=22 pso_serial#=115 2025-05-17 13:36:40.960411 :984D9E2D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10888 osp_idx=0 osp_ver=202330 osp_pg=0 (spawn #191332) 2025-05-17 13:36:40.960412 :984D9E2E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 13:36:40.960413 :984D9E2F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=20 time=464810829 2025-05-17 13:36:40.960413 :984D9E30:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 13:36:40.960413 :984D9E31:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 13:36:40.960414 :984D9E32:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 13:36:40.960414 :984D9E33:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 13:36:40.960414 :984D9E34:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 13:36:40.960902 :984D9E35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 13:36:40.960951 :984D9E3C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 13:46:41.894093 :984E635F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 13:46:42.289357 :984E63A3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 13:46:42.285 2025-05-17 13:46:42.289359 :984E63A4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10684 pso_num=22 pso_serial#=116 2025-05-17 13:46:42.289503 :984E63A5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10684 osp_idx=0 osp_ver=202331 osp_pg=0 (spawn #191354) 2025-05-17 13:46:42.289503 :984E63A6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 13:46:42.289504 :984E63A7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=21 time=465412157 2025-05-17 13:46:42.289504 :984E63A8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 13:46:42.289505 :984E63A9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 13:46:42.289505 :984E63AA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 13:46:42.289505 :984E63AB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 13:46:42.289506 :984E63AC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 13:46:42.289995 :984E63AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 13:46:42.290042 :984E63B4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 13:56:43.235198 :984F29AF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 13:56:43.567963 :984F29EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 13:56:43.563 2025-05-17 13:56:43.567965 :984F29ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13868 pso_num=22 pso_serial#=117 2025-05-17 13:56:43.568102 :984F29EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13868 osp_idx=0 osp_ver=202332 osp_pg=0 (spawn #191377) 2025-05-17 13:56:43.568103 :984F29EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 13:56:43.568104 :984F29F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=22 time=466013438 2025-05-17 13:56:43.568104 :984F29F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 13:56:43.568104 :984F29F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 13:56:43.568105 :984F29F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 13:56:43.568105 :984F29F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 13:56:43.568105 :984F29F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 13:56:43.568587 :984F29F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 13:56:43.568633 :984F29FD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-17 14:06:44.559558 :984FF778:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 14:06:44.923532 :984FF7B9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 14:06:44.919 2025-05-17 14:06:44.923534 :984FF7BA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12872 pso_num=22 pso_serial#=118 2025-05-17 14:06:44.923667 :984FF7BB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12872 osp_idx=0 osp_ver=202333 osp_pg=0 (spawn #191403) 2025-05-17 14:06:44.923668 :984FF7BC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 14:06:44.923668 :984FF7BD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=23 time=466614797 2025-05-17 14:06:44.923669 :984FF7BE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 14:06:44.923669 :984FF7BF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 14:06:44.923669 :984FF7C0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 14:06:44.923670 :984FF7C1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 14:06:44.923670 :984FF7C2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 14:06:44.924212 :984FF7C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 14:06:44.924255 :984FF7CB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 12872, W000)