Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250824081207\orcl_w000_21876_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:35248M/63366M, Ph+PgF:40377M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21876, image: ORACLE.EXE (W000) *** 2025-08-24 08:12:07.610 *** SESSION ID:(177.48291) 2025-08-24 08:12:07.610 *** 2025-08-24 08:12:07.610 Process diagnostic dump for ORACLE.EXE (W000), OS id=21876, pid: 22, proc_ser: 156, sid: 177, sess_ser: 48291 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=4.019229 sec, exc=4.019229 sec, total=4.019229 sec wait times: max=5.000000 sec, heur=5 min 4 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.000030 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=60 seq_num=61 snap_id=1 wait times: snap=5.014781 sec, exc=5.014781 sec, total=5.014781 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=5.008361 sec, exc=5.008361 sec, total=5.008361 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=5.000360 sec, exc=5.000360 sec, total=5.000360 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.000438 sec, exc=5.000438 sec, total=5.000438 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.003962 sec, exc=5.003962 sec, total=5.003962 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.004649 sec, exc=5.004649 sec, total=5.004649 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=54 seq_num=55 snap_id=1 wait times: snap=5.014716 sec, exc=5.014716 sec, total=5.014716 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=5.004634 sec, exc=5.004634 sec, total=5.004634 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=5.004636 sec, exc=5.004636 sec, total=5.004636 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 177 serial 48291 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 08:10:07 - 08:12:07] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-24 08:12:07.610 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21876, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-24 05:46:44.152248 :C3E2AD9B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 05:46:44.152248 :C3E2AD9C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 05:46:44.152249 :C3E2AD9D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 05:46:44.152748 :C3E2AD9E: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-08-24 05:46:44.152793 :C3E2ADA7: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-08-24 05:56:45.074117 :C3E374F6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 05:56:45.438028 :C3E37552:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 05:56:45.433 2025-08-24 05:56:45.438030 :C3E37553:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21512 pso_num=22 pso_serial#=142 2025-08-24 05:56:45.438171 :C3E37554:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21512 osp_idx=0 osp_ver=544894 osp_pg=0 (spawn #520257) 2025-08-24 05:56:45.438172 :C3E37555:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 05:56:45.438172 :C3E37556:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=30 time=400707455 2025-08-24 05:56:45.438173 :C3E37557:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 05:56:45.438173 :C3E37558:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 05:56:45.438173 :C3E37559:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 05:56:45.438174 :C3E3755A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 05:56:45.438174 :C3E3755B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 05:56:45.438679 :C3E3755C: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-08-24 05:56:45.438723 :C3E37562: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-08-24 06:06:46.267902 :C3E45486:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 06:06:46.711133 :C3E4550E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 06:06:46.706 2025-08-24 06:06:46.711135 :C3E4550F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16844 pso_num=22 pso_serial#=143 2025-08-24 06:06:46.711270 :C3E45510:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16844 osp_idx=0 osp_ver=544895 osp_pg=0 (spawn #520286) 2025-08-24 06:06:46.711271 :C3E45511:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 06:06:46.711271 :C3E45512:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=31 time=401308721 2025-08-24 06:06:46.711271 :C3E45513:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 06:06:46.711272 :C3E45514:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 06:06:46.711272 :C3E45515:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 06:06:46.711272 :C3E45516:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 06:06:46.711273 :C3E45517:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 06:06:46.711765 :C3E45518: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-08-24 06:06:46.711811 :C3E4551F: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-08-24 06:16:47.632168 :C3E52165:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 06:16:48.138029 :C3E521F4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 06:16:48.133 2025-08-24 06:16:48.138032 :C3E521F5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21740 pso_num=22 pso_serial#=144 2025-08-24 06:16:48.138182 :C3E521F6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21740 osp_idx=0 osp_ver=544896 osp_pg=0 (spawn #520309) 2025-08-24 06:16:48.138182 :C3E521F7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 06:16:48.138183 :C3E521F8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=32 time=401910143 2025-08-24 06:16:48.138183 :C3E521F9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 06:16:48.138183 :C3E521FA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 06:16:48.138184 :C3E521FB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 06:16:48.138184 :C3E521FC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 06:16:48.138184 :C3E521FD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 06:16:48.138674 :C3E521FE: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-08-24 06:16:48.138723 :C3E52206: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-08-24 06:26:49.046609 :C3E5E9AD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 06:26:49.535179 :C3E5EA06:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 06:26:49.530 2025-08-24 06:26:49.535181 :C3E5EA07:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20428 pso_num=22 pso_serial#=145 2025-08-24 06:26:49.535332 :C3E5EA08:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20428 osp_idx=0 osp_ver=544897 osp_pg=0 (spawn #520332) 2025-08-24 06:26:49.535333 :C3E5EA09:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 06:26:49.535333 :C3E5EA0A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=33 time=402511549 2025-08-24 06:26:49.535334 :C3E5EA0B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 06:26:49.535334 :C3E5EA0C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 06:26:49.535334 :C3E5EA0D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 06:26:49.535335 :C3E5EA0E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 06:26:49.535335 :C3E5EA0F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 06:26:49.535833 :C3E5EA10: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-08-24 06:26:49.535880 :C3E5EA1A: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-08-24 06:36:50.469318 :C3E6B291:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 06:36:51.037292 :C3E6B306:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 06:36:51.034 2025-08-24 06:36:51.037294 :C3E6B307:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17416 pso_num=22 pso_serial#=146 2025-08-24 06:36:51.037436 :C3E6B308:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17416 osp_idx=0 osp_ver=544898 osp_pg=0 (spawn #520354) 2025-08-24 06:36:51.037437 :C3E6B309:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 06:36:51.037437 :C3E6B30A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=34 time=403113049 2025-08-24 06:36:51.037438 :C3E6B30B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 06:36:51.037438 :C3E6B30C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 06:36:51.037439 :C3E6B30D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 06:36:51.037439 :C3E6B30E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 06:36:51.037439 :C3E6B30F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 06:36:51.037932 :C3E6B310: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-08-24 06:36:51.037977 :C3E6B318: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-08-24 06:46:51.926592 :C3E77BEA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 06:46:52.556159 :C3E77C56:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 06:46:52.551 2025-08-24 06:46:52.556161 :C3E77C57:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12820 pso_num=22 pso_serial#=147 2025-08-24 06:46:52.556308 :C3E77C58:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12820 osp_idx=0 osp_ver=544899 osp_pg=0 (spawn #520377) 2025-08-24 06:46:52.556309 :C3E77C59:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 06:46:52.556309 :C3E77C5A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=35 time=403714565 2025-08-24 06:46:52.556311 :C3E77C5B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 06:46:52.556312 :C3E77C5C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 06:46:52.556312 :C3E77C5D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 06:46:52.556313 :C3E77C5E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 06:46:52.556313 :C3E77C5F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 06:46:52.556825 :C3E77C60: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-08-24 06:46:52.556873 :C3E77C68: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-08-24 06:53:32.115274 :C3E80080: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-08-24 06:56:53.926127 :C3E84433: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-08-24 07:06:54.838347 :C3E916C4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 07:06:55.340021 :C3E91736:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 07:06:55.335 2025-08-24 07:06:55.340023 :C3E91737:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22152 pso_num=22 pso_serial#=148 2025-08-24 07:06:55.340157 :C3E91738:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22152 osp_idx=0 osp_ver=544900 osp_pg=0 (spawn #520424) 2025-08-24 07:06:55.340158 :C3E91739:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:06:55.340158 :C3E9173A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=36 time=404917346 2025-08-24 07:06:55.340159 :C3E9173B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:06:55.340159 :C3E9173C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:06:55.340159 :C3E9173D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:06:55.340160 :C3E9173E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:06:55.340160 :C3E9173F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:06:55.340645 :C3E91740: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-08-24 07:06:55.340687 :C3E91747: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-08-24 07:16:56.173221 :C3E9E1C9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 07:16:56.584256 :C3E9E23C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 07:16:56.579 2025-08-24 07:16:56.584259 :C3E9E23D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12108 pso_num=22 pso_serial#=149 2025-08-24 07:16:56.584400 :C3E9E23E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12108 osp_idx=0 osp_ver=544901 osp_pg=0 (spawn #520447) 2025-08-24 07:16:56.584401 :C3E9E23F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:16:56.584401 :C3E9E240:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=37 time=405518596 2025-08-24 07:16:56.584402 :C3E9E241:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:16:56.584402 :C3E9E242:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:16:56.584402 :C3E9E243:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:16:56.584403 :C3E9E244:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:16:56.584403 :C3E9E245:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:16:56.584942 :C3E9E246: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-08-24 07:16:56.584984 :C3E9E24D: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-08-24 07:26:57.455347 :C3EAACD9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 07:26:57.913068 :C3EAAD41:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 07:26:57.908 2025-08-24 07:26:57.913070 :C3EAAD42:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20900 pso_num=22 pso_serial#=150 2025-08-24 07:26:57.913208 :C3EAAD43:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20900 osp_idx=0 osp_ver=544902 osp_pg=0 (spawn #520470) 2025-08-24 07:26:57.913209 :C3EAAD44:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:26:57.913209 :C3EAAD45:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=38 time=406119924 2025-08-24 07:26:57.913209 :C3EAAD46:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:26:57.913210 :C3EAAD47:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:26:57.913210 :C3EAAD48:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:26:57.913211 :C3EAAD49:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:26:57.913211 :C3EAAD4A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:26:57.913700 :C3EAAD4B: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-08-24 07:26:57.913752 :C3EAAD52: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-08-24 07:36:58.904992 :C3EB77D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 07:36:59.362144 :C3EB783E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 07:36:59.357 2025-08-24 07:36:59.362146 :C3EB783F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20756 pso_num=22 pso_serial#=151 2025-08-24 07:36:59.362286 :C3EB7840:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20756 osp_idx=0 osp_ver=544903 osp_pg=0 (spawn #520490) 2025-08-24 07:36:59.362286 :C3EB7841:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:36:59.362287 :C3EB7842:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=39 time=406721377 2025-08-24 07:36:59.362287 :C3EB7843:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:36:59.362287 :C3EB7844:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:36:59.362288 :C3EB7845:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:36:59.362288 :C3EB7846:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:36:59.362288 :C3EB7847:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:36:59.362787 :C3EB7848: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-08-24 07:36:59.362835 :C3EB784E: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-08-24 07:47:00.268453 :C3EC4363:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 07:47:00.695005 :C3EC43B6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 07:47:00.690 2025-08-24 07:47:00.695007 :C3EC43B7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20392 pso_num=22 pso_serial#=152 2025-08-24 07:47:00.695158 :C3EC43B8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20392 osp_idx=0 osp_ver=544904 osp_pg=0 (spawn #520513) 2025-08-24 07:47:00.695159 :C3EC43B9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:47:00.695159 :C3EC43BA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=407322705 2025-08-24 07:47:00.695160 :C3EC43BB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:47:00.695160 :C3EC43BC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:47:00.695161 :C3EC43BD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:47:00.695161 :C3EC43BE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:47:00.695161 :C3EC43BF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:47:00.695695 :C3EC43C0: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-08-24 07:47:00.695741 :C3EC43C8: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-08-24 07:57:01.659179 :C3ED0E42:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 07:57:02.007840 :C3ED0E7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 07:57:02.003 2025-08-24 07:57:02.007843 :C3ED0E80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14400 pso_num=22 pso_serial#=153 2025-08-24 07:57:02.007982 :C3ED0E81:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14400 osp_idx=0 osp_ver=544905 osp_pg=0 (spawn #520536) 2025-08-24 07:57:02.007983 :C3ED0E82:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 07:57:02.007983 :C3ED0E83:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=407924018 2025-08-24 07:57:02.007984 :C3ED0E84:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 07:57:02.007984 :C3ED0E85:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 07:57:02.007984 :C3ED0E86:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 07:57:02.007985 :C3ED0E87:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 07:57:02.007985 :C3ED0E88:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 07:57:02.008498 :C3ED0E89: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-08-24 07:57:02.008561 :C3ED0E93: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-08-24 08:07:02.925104 :C3EDE4EE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 08:07:02.969691 :C3EDE4F1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 08:07:02.956 2025-08-24 08:07:02.969692 :C3EDE4F2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21964 pso_num=22 pso_serial#=154 2025-08-24 08:07:02.969838 :C3EDE4F3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21964 (legacy spawn) 2025-08-24 08:07:02.976408 :C3EDE4F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 08:07:02.976577 :C3EDE4F9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 08:07:02.977368 :C3EDE4FE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 08:07:02.986015 :C3EDE501:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 08:07:02.971 2025-08-24 08:07:02.986017 :C3EDE502:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22116 pso_num=22 pso_serial#=155 2025-08-24 08:07:02.986151 :C3EDE503:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22116 (legacy spawn) 2025-08-24 08:07:02.992639 :C3EDE504:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-24 08:07:02.992799 :C3EDE50D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-24 08:07:02.993651 :C3EDE50E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-24 08:07:03.158623 :C3EDE534:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-24 08:07:03.153 2025-08-24 08:07:03.158626 :C3EDE535:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21876 pso_num=22 pso_serial#=156 2025-08-24 08:07:03.158772 :C3EDE536:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21876 osp_idx=0 osp_ver=544908 osp_pg=0 (spawn #520561) 2025-08-24 08:07:03.158772 :C3EDE537:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-24 08:07:03.158772 :C3EDE538:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=408525174 2025-08-24 08:07:03.158773 :C3EDE539:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 08:07:03.158773 :C3EDE53A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 08:07:03.158774 :C3EDE53B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 08:07:03.158774 :C3EDE53C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 08:07:03.158774 :C3EDE53D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 08:07:03.159261 :C3EDE53E: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-08-24 08:07:03.159307 :C3EDE546: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: 21876, W000)