Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913060005\orcl_w000_25008_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:34819M/63366M, Ph+PgF:39475M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 25008, image: ORACLE.EXE (W000) *** 2025-09-13 06:00:06.021 *** SESSION ID:(201.61129) 2025-09-13 06:00:06.021 *** 2025-09-13 06:00:06.021 Process diagnostic dump for ORACLE.EXE (W000), OS id=25008, pid: 25, proc_ser: 184, sid: 201, sess_ser: 61129 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=3.916648 sec, exc=3.916648 sec, total=3.916648 sec wait times: max=5.000000 sec, heur=4 min 44 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.000025 sec since current wait 0: 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.005318 sec, exc=5.005318 sec, total=5.005318 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: 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.013852 sec, exc=5.013852 sec, total=5.013852 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 2: 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.009196 sec, exc=5.009196 sec, total=5.009196 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 3: 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.010147 sec, exc=5.010147 sec, total=5.010147 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: 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.014754 sec, exc=5.014754 sec, total=5.014754 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: 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.007226 sec, exc=5.007226 sec, total=5.007226 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=5.001436 sec, exc=5.001436 sec, total=5.001436 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.001978 sec, exc=5.001978 sec, total=5.001978 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=48 seq_num=49 snap_id=1 wait times: snap=5.009129 sec, exc=5.009129 sec, total=5.009129 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=5.010167 sec, exc=5.010167 sec, total=5.010167 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 201 serial 61129 --------------------------------------------------- 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, 05:58:06 - 06:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 06:00:06.021 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 25008, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 04:02:08.078088 :CCB195AC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:02:08.078088 :CCB195AD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=2121779377 2025-09-13 04:02:08.078089 :CCB195AE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:02:08.078089 :CCB195AF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:02:08.078089 :CCB195B0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:02:08.078090 :CCB195B1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:02:08.078090 :CCB195B2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:02:08.078576 :CCB195B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:02:38.121861 :CCB19FC9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:02:38.956280 :CCB1A0B1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:02:38.944 2025-09-13 04:02:38.956281 :CCB1A0B2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25200 pso_num=25 pso_serial#=171 2025-09-13 04:02:38.956431 :CCB1A0B3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25200 osp_idx=23 osp_ver=294454 osp_pg=0 (spawn #586668) 2025-09-13 04:02:38.956431 :CCB1A0B4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:02:38.956432 :CCB1A0B5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=2121810252 2025-09-13 04:02:38.956432 :CCB1A0B6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:02:38.956433 :CCB1A0B7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:02:38.956433 :CCB1A0B8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:02:38.956434 :CCB1A0B9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:02:38.956434 :CCB1A0BA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:02:38.957055 :CCB1A0BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:02:38.957108 :CCB1A0C1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 04:02:38.965706 :CCB1A0D6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:03:08.155477 :CCB1AA22:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:03:08.145 2025-09-13 04:03:08.155479 :CCB1AA23:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22220 pso_num=25 pso_serial#=172 2025-09-13 04:03:08.155597 :CCB1AA24:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22220 osp_idx=23 osp_ver=294455 osp_pg=0 (spawn #586670) 2025-09-13 04:03:08.155597 :CCB1AA25:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:03:08.155597 :CCB1AA26:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=2121839455 2025-09-13 04:03:08.155598 :CCB1AA27:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:03:08.155598 :CCB1AA28:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:03:08.155599 :CCB1AA29:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:03:08.155599 :CCB1AA2A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:03:08.155599 :CCB1AA2B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:03:08.156073 :CCB1AA2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:03:38.231286 :CCB1B431:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:04:08.249542 :CCB1BE46:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:04:08.238 2025-09-13 04:04:08.249544 :CCB1BE47:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20404 pso_num=25 pso_serial#=173 2025-09-13 04:04:08.249665 :CCB1BE48:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20404 osp_idx=23 osp_ver=294456 osp_pg=0 (spawn #586672) 2025-09-13 04:04:08.249666 :CCB1BE49:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:04:08.249667 :CCB1BE4A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=2121899549 2025-09-13 04:04:08.249667 :CCB1BE4B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:04:08.249668 :CCB1BE4C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:04:08.249668 :CCB1BE4D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:04:08.249668 :CCB1BE4E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:04:08.249669 :CCB1BE4F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:04:08.250154 :CCB1BE50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:04:38.338063 :CCB1C835:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:05:08.556077 :CCB1D2AC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:05:08.551 2025-09-13 04:05:08.556079 :CCB1D2AD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23648 pso_num=25 pso_serial#=174 2025-09-13 04:05:08.556216 :CCB1D2AE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23648 osp_idx=23 osp_ver=294457 osp_pg=0 (spawn #586675) 2025-09-13 04:05:08.556217 :CCB1D2AF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:05:08.556217 :CCB1D2B0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=2121959862 2025-09-13 04:05:08.556218 :CCB1D2B1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:05:08.556218 :CCB1D2B2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:05:08.556219 :CCB1D2B3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:05:08.556219 :CCB1D2B4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:05:08.556220 :CCB1D2B5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:05:08.556717 :CCB1D2B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:05:08.556764 :CCB1D2BE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 04:15:09.378812 :CCB2A233:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:15:09.680520 :CCB2A273:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:15:09.675 2025-09-13 04:15:09.680523 :CCB2A274:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25260 pso_num=25 pso_serial#=175 2025-09-13 04:15:09.680665 :CCB2A275:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25260 osp_idx=23 osp_ver=294458 osp_pg=0 (spawn #586698) 2025-09-13 04:15:09.680666 :CCB2A276:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:15:09.680667 :CCB2A277:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=2122560987 2025-09-13 04:15:09.680667 :CCB2A278:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:15:09.680667 :CCB2A279:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:15:09.680668 :CCB2A27A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:15:09.680668 :CCB2A27B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:15:09.680669 :CCB2A27C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:15:09.681185 :CCB2A27D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:15:09.681230 :CCB2A285:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 04:25:10.569580 :CCB36F3B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:25:10.840305 :CCB36F8A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:25:10.835 2025-09-13 04:25:10.840307 :CCB36F8B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22436 pso_num=25 pso_serial#=176 2025-09-13 04:25:10.840456 :CCB36F8C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22436 osp_idx=23 osp_ver=294459 osp_pg=0 (spawn #586721) 2025-09-13 04:25:10.840457 :CCB36F8D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:25:10.840458 :CCB36F8E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=2123162143 2025-09-13 04:25:10.840458 :CCB36F8F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:25:10.840459 :CCB36F90:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:25:10.840459 :CCB36F91:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:25:10.840459 :CCB36F92:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:25:10.840460 :CCB36F93:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:25:10.840978 :CCB36F94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:25:10.841032 :CCB36F9D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 04:35:11.802072 :CCB43993:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:35:12.103783 :CCB439D2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:35:12.098 2025-09-13 04:35:12.103785 :CCB439D3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24592 pso_num=25 pso_serial#=177 2025-09-13 04:35:12.103917 :CCB439D4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24592 osp_idx=23 osp_ver=294460 osp_pg=0 (spawn #586743) 2025-09-13 04:35:12.103918 :CCB439D5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:35:12.103919 :CCB439D6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=221 time=2123763408 2025-09-13 04:35:12.103919 :CCB439D7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:35:12.103919 :CCB439D8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:35:12.103920 :CCB439D9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:35:12.103920 :CCB439DA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:35:12.103920 :CCB439DB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:35:12.104421 :CCB439DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:35:12.104468 :CCB439E6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 04:45:12.926872 :CCB503F2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:45:13.384902 :CCB50442:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:45:13.380 2025-09-13 04:45:13.384904 :CCB50443:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23876 pso_num=25 pso_serial#=178 2025-09-13 04:45:13.385052 :CCB50444:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23876 osp_idx=23 osp_ver=294461 osp_pg=0 (spawn #586766) 2025-09-13 04:45:13.385053 :CCB50445:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:45:13.385053 :CCB50446:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=2124364690 2025-09-13 04:45:13.385053 :CCB50447:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:45:13.385054 :CCB50448:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:45:13.385054 :CCB50449:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:45:13.385054 :CCB5044A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:45:13.385055 :CCB5044B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:45:13.385559 :CCB5044C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:45:13.385608 :CCB50453:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 04:55:14.222391 :CCB5CE5D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 04:55:14.586524 :CCB5CE9F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 04:55:14.581 2025-09-13 04:55:14.586527 :CCB5CEA0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25444 pso_num=25 pso_serial#=179 2025-09-13 04:55:14.586683 :CCB5CEA1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25444 osp_idx=23 osp_ver=294462 osp_pg=0 (spawn #586789) 2025-09-13 04:55:14.586684 :CCB5CEA2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 04:55:14.586685 :CCB5CEA3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=223 time=2124965893 2025-09-13 04:55:14.586685 :CCB5CEA4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 04:55:14.586685 :CCB5CEA5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 04:55:14.586686 :CCB5CEA6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 04:55:14.586686 :CCB5CEA7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 04:55:14.586686 :CCB5CEA8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 04:55:14.587218 :CCB5CEA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 04:55:14.587269 :CCB5CEB0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:05:15.366820 :CCB699DD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 05:05:15.730878 :CCB69CE4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 05:05:15.725 2025-09-13 05:05:15.730880 :CCB69CE5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23404 pso_num=25 pso_serial#=180 2025-09-13 05:05:15.731024 :CCB69CE6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23404 osp_idx=23 osp_ver=294463 osp_pg=0 (spawn #586814) 2025-09-13 05:05:15.731025 :CCB69CE7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:05:15.731025 :CCB69CE8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=224 time=2125567033 2025-09-13 05:05:15.731026 :CCB69CE9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:05:15.731026 :CCB69CEA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:05:15.731027 :CCB69CEB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:05:15.731027 :CCB69CEC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:05:15.731027 :CCB69CED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:05:15.731530 :CCB69CEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 05:05:15.731578 :CCB69CF7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:15:16.512942 :CCB76C52:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 05:15:16.877286 :CCB76C7E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 05:15:16.872 2025-09-13 05:15:16.877289 :CCB76C7F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23904 pso_num=25 pso_serial#=181 2025-09-13 05:15:16.877440 :CCB76C80:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23904 osp_idx=23 osp_ver=294464 osp_pg=0 (spawn #586837) 2025-09-13 05:15:16.877441 :CCB76C81:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:15:16.877441 :CCB76C82:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=225 time=2126168174 2025-09-13 05:15:16.877442 :CCB76C83:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:15:16.877442 :CCB76C84:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:15:16.877443 :CCB76C85:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:15:16.877443 :CCB76C86:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:15:16.877443 :CCB76C87:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:15:16.877987 :CCB76C88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 05:15:16.878036 :CCB76C90:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:25:17.775077 :CCB836AC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 05:25:18.092201 :CCB836E5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 05:25:18.087 2025-09-13 05:25:18.092203 :CCB836E6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22152 pso_num=25 pso_serial#=182 2025-09-13 05:25:18.092343 :CCB836E7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22152 osp_idx=23 osp_ver=294465 osp_pg=0 (spawn #586860) 2025-09-13 05:25:18.092344 :CCB836E8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:25:18.092344 :CCB836E9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=226 time=2126769393 2025-09-13 05:25:18.092345 :CCB836EA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:25:18.092345 :CCB836EB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:25:18.092345 :CCB836EC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:25:18.092346 :CCB836ED:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:25:18.092346 :CCB836EE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:25:18.092856 :CCB836EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 05:25:18.092904 :CCB836F6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:35:18.834472 :CCB90044:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 05:35:19.292760 :CCB90072:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 05:35:19.288 2025-09-13 05:35:19.292763 :CCB90073:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25272 pso_num=25 pso_serial#=183 2025-09-13 05:35:19.292921 :CCB90074:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25272 osp_idx=23 osp_ver=294466 osp_pg=0 (spawn #586882) 2025-09-13 05:35:19.292922 :CCB90075:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:35:19.292923 :CCB90076:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=2127370596 2025-09-13 05:35:19.292924 :CCB90077:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:35:19.292926 :CCB90078:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:35:19.292926 :CCB90079:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:35:19.292926 :CCB9007A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:35:19.292927 :CCB9007B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:35:19.293474 :CCB9007C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 05:35:19.293521 :CCB90084:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:40:15.850107 :CCB9637B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:45:20.513026 :CCB9CAE6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-13 05:55:21.293846 :CCBA9508:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 05:55:21.689014 :CCBA9537:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 05:55:21.684 2025-09-13 05:55:21.689017 :CCBA9538:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25008 pso_num=25 pso_serial#=184 2025-09-13 05:55:21.689155 :CCBA9539:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25008 osp_idx=23 osp_ver=294467 osp_pg=0 (spawn #586927) 2025-09-13 05:55:21.689156 :CCBA953A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:55:21.689156 :CCBA953B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=2128572987 2025-09-13 05:55:21.689157 :CCBA953C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:55:21.689158 :CCBA953D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:55:21.689158 :CCBA953E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:55:21.689158 :CCBA953F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:55:21.689159 :CCBA9540:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:55:21.689719 :CCBA9541:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 05:55:21.689764 :CCBA9549:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 25008, W000)