Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250720081839\orcl_w000_18552_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:36152M/63366M, Ph+PgF:42017M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 18552, image: ORACLE.EXE (W000) *** 2025-07-20 08:18:39.786 *** SESSION ID:(177.26279) 2025-07-20 08:18:39.786 *** 2025-07-20 08:18:39.786 Process diagnostic dump for ORACLE.EXE (W000), OS id=18552, pid: 22, proc_ser: 45, sid: 177, sess_ser: 26279 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=1.896083 sec, exc=1.896083 sec, total=1.896083 sec wait times: max=5.000000 sec, heur=6 min 22 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=76 seq_num=77 snap_id=1 wait times: snap=5.010017 sec, exc=5.010017 sec, total=5.010017 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.009230 sec, exc=5.009230 sec, total=5.009230 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.015241 sec, exc=5.015241 sec, total=5.015241 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.003231 sec, exc=5.003231 sec, total=5.003231 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=72 seq_num=73 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.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.014638 sec, exc=5.014638 sec, total=5.014638 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.000178 sec, exc=5.000178 sec, total=5.000178 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.014738 sec, exc=5.014738 sec, total=5.014738 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.014745 sec, exc=5.014745 sec, total=5.014745 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000045 sec of elapsed time Sampled Session History of session 177 serial 26279 --------------------------------------------------- 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:16:39 - 08:18:39] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-20 08:18:39.786 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 18552, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-20 05:41:57.150690 :B46D4F82:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 05:41:57.150690 :B46D4F83:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 05:41:57.151216 :B46D4F84: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-07-20 05:41:57.151263 :B46D4F8D: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-07-20 05:51:58.166608 :B46E144D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 05:51:58.468020 :B46E14B2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 05:51:58.463 2025-07-20 05:51:58.468022 :B46E14B3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7628 pso_num=22 pso_serial#=32 2025-07-20 05:51:58.468165 :B46E14B4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7628 osp_idx=0 osp_ver=424424 osp_pg=0 (spawn #403587) 2025-07-20 05:51:58.468165 :B46E14B5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 05:51:58.468166 :B46E14B6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=49 time=1671494361 2025-07-20 05:51:58.468166 :B46E14B7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 05:51:58.468167 :B46E14B8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 05:51:58.468167 :B46E14B9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 05:51:58.468167 :B46E14BA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 05:51:58.468167 :B46E14BB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 05:51:58.468672 :B46E14BC: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-07-20 05:51:58.468717 :B46E14C4: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-07-20 06:01:59.322604 :B46EE651:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 06:01:59.775170 :B46EE68E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 06:01:59.770 2025-07-20 06:01:59.775172 :B46EE68F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9028 pso_num=22 pso_serial#=33 2025-07-20 06:01:59.775353 :B46EE690:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9028 osp_idx=0 osp_ver=424425 osp_pg=0 (spawn #403618) 2025-07-20 06:01:59.775354 :B46EE691:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:01:59.775355 :B46EE692:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=50 time=1672095673 2025-07-20 06:01:59.775355 :B46EE693:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:01:59.775356 :B46EE694:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:01:59.775356 :B46EE695:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:01:59.775357 :B46EE696:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:01:59.775357 :B46EE697:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:01:59.775873 :B46EE698: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-07-20 06:01:59.775921 :B46EE6A0: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-07-20 06:12:00.679730 :B46FB672:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 06:12:01.137554 :B46FB6C8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 06:12:01.132 2025-07-20 06:12:01.137556 :B46FB6C9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14912 pso_num=22 pso_serial#=34 2025-07-20 06:12:01.137693 :B46FB6CA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14912 osp_idx=0 osp_ver=424426 osp_pg=0 (spawn #403641) 2025-07-20 06:12:01.137694 :B46FB6CB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:12:01.137694 :B46FB6CC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=51 time=1672697033 2025-07-20 06:12:01.137694 :B46FB6CD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:12:01.137695 :B46FB6CE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:12:01.137695 :B46FB6CF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:12:01.137695 :B46FB6D0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:12:01.137696 :B46FB6D1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:12:01.138204 :B46FB6D2: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-07-20 06:12:01.138250 :B46FB6D9: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-07-20 06:22:01.989455 :B4708153:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 06:22:02.478518 :B4708190:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 06:22:02.473 2025-07-20 06:22:02.478520 :B4708191:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18908 pso_num=22 pso_serial#=35 2025-07-20 06:22:02.478665 :B4708192:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18908 osp_idx=0 osp_ver=424427 osp_pg=0 (spawn #403664) 2025-07-20 06:22:02.478666 :B4708193:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:22:02.478666 :B4708194:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=52 time=1673298376 2025-07-20 06:22:02.478667 :B4708195:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:22:02.478667 :B4708196:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:22:02.478667 :B4708197:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:22:02.478667 :B4708198:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:22:02.478668 :B4708199:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:22:02.479176 :B470819A: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-07-20 06:22:02.479221 :B47081A1: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-07-20 06:32:03.372746 :B471490F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 06:32:03.878552 :B4714958:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 06:32:03.873 2025-07-20 06:32:03.878554 :B4714959:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17772 pso_num=22 pso_serial#=36 2025-07-20 06:32:03.878689 :B471495A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17772 osp_idx=0 osp_ver=424428 osp_pg=0 (spawn #403686) 2025-07-20 06:32:03.878689 :B471495B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:32:03.878690 :B471495C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=53 time=1673899767 2025-07-20 06:32:03.878690 :B471495D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:32:03.878690 :B471495E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:32:03.878691 :B471495F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:32:03.878691 :B4714960:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:32:03.878691 :B4714961:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:32:03.879203 :B4714962: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-07-20 06:32:03.879249 :B471496A: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-07-20 06:42:04.862296 :B472122E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 06:42:05.320876 :B4721277:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 06:42:05.315 2025-07-20 06:42:05.320878 :B4721278:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18976 pso_num=22 pso_serial#=37 2025-07-20 06:42:05.321015 :B4721279:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18976 osp_idx=0 osp_ver=424429 osp_pg=0 (spawn #403709) 2025-07-20 06:42:05.321016 :B472127A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:42:05.321016 :B472127B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=54 time=1674501220 2025-07-20 06:42:05.321016 :B472127C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:42:05.321017 :B472127D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:42:05.321017 :B472127E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:42:05.321017 :B472127F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:42:05.321017 :B4721280:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:42:05.321508 :B4721281: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-07-20 06:42:05.321553 :B4721289: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-07-20 06:52:06.226471 :B472D98D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 06:52:06.809178 :B472D9DD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 06:52:06.804 2025-07-20 06:52:06.809179 :B472D9DE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14704 pso_num=22 pso_serial#=38 2025-07-20 06:52:06.809313 :B472D9DF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14704 osp_idx=0 osp_ver=424430 osp_pg=0 (spawn #403732) 2025-07-20 06:52:06.809314 :B472D9E0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:52:06.809315 :B472D9E1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=55 time=1675102704 2025-07-20 06:52:06.809316 :B472D9E2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:52:06.809317 :B472D9E3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:52:06.809317 :B472D9E4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:52:06.809318 :B472D9E5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:52:06.809318 :B472D9E6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:52:06.809809 :B472D9E7: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-07-20 06:52:06.809854 :B472D9EE: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-07-20 07:02:07.669053 :B473A26F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 07:02:08.127966 :B473A2B8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 07:02:08.122 2025-07-20 07:02:08.127969 :B473A2B9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19060 pso_num=22 pso_serial#=39 2025-07-20 07:02:08.128117 :B473A2BA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19060 osp_idx=0 osp_ver=424431 osp_pg=0 (spawn #403757) 2025-07-20 07:02:08.128117 :B473A2BB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 07:02:08.128117 :B473A2BC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=56 time=1675704017 2025-07-20 07:02:08.128117 :B473A2BD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 07:02:08.128118 :B473A2BE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 07:02:08.128118 :B473A2BF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 07:02:08.128118 :B473A2C0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 07:02:08.128119 :B473A2C1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 07:02:08.128641 :B473A2C2: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-07-20 07:02:08.128693 :B473A2C8: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-07-20 07:08:55.658794 :B4742F96: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-07-20 07:12:09.457351 :B47471CB: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-07-20 07:22:10.383050 :B4753757:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 07:22:10.808911 :B4753794:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 07:22:10.804 2025-07-20 07:22:10.808913 :B4753795:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8372 pso_num=22 pso_serial#=40 2025-07-20 07:22:10.809091 :B4753796:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8372 osp_idx=0 osp_ver=424432 osp_pg=0 (spawn #403802) 2025-07-20 07:22:10.809092 :B4753797:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 07:22:10.809092 :B4753798:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=57 time=1676906704 2025-07-20 07:22:10.809092 :B4753799:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 07:22:10.809092 :B475379A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 07:22:10.809093 :B475379B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 07:22:10.809093 :B475379C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 07:22:10.809094 :B475379D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 07:22:10.809616 :B475379E: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-07-20 07:22:10.809663 :B47537A6: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-07-20 07:32:11.630588 :B475FD30:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 07:32:12.108922 :B475FD79:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 07:32:12.104 2025-07-20 07:32:12.108925 :B475FD7A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8140 pso_num=22 pso_serial#=41 2025-07-20 07:32:12.109060 :B475FD7B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8140 osp_idx=0 osp_ver=424433 osp_pg=0 (spawn #403824) 2025-07-20 07:32:12.109061 :B475FD7C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 07:32:12.109062 :B475FD7D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=58 time=1677508001 2025-07-20 07:32:12.109062 :B475FD7E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 07:32:12.109062 :B475FD7F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 07:32:12.109063 :B475FD80:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 07:32:12.109063 :B475FD81:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 07:32:12.109064 :B475FD82:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 07:32:12.109548 :B475FD83: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-07-20 07:32:12.109595 :B475FD8C: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-07-20 07:42:13.002568 :B476C3A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 07:42:13.444237 :B476C3E5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 07:42:13.439 2025-07-20 07:42:13.444239 :B476C3E6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9372 pso_num=22 pso_serial#=42 2025-07-20 07:42:13.444380 :B476C3E7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9372 osp_idx=0 osp_ver=424434 osp_pg=0 (spawn #403845) 2025-07-20 07:42:13.444381 :B476C3E8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 07:42:13.444381 :B476C3E9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=59 time=1678109345 2025-07-20 07:42:13.444382 :B476C3EA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 07:42:13.444382 :B476C3EB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 07:42:13.444382 :B476C3EC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 07:42:13.444383 :B476C3ED:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 07:42:13.444383 :B476C3EE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 07:42:13.444880 :B476C3EF: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-07-20 07:42:13.444927 :B476C3F7: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-07-20 07:52:14.388498 :B4778955:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 07:52:14.741936 :B47789B1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 07:52:14.737 2025-07-20 07:52:14.741937 :B47789B2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15012 pso_num=22 pso_serial#=43 2025-07-20 07:52:14.742068 :B47789B3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15012 osp_idx=0 osp_ver=424435 osp_pg=0 (spawn #403869) 2025-07-20 07:52:14.742068 :B47789B4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 07:52:14.742069 :B47789B5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=60 time=1678710642 2025-07-20 07:52:14.742069 :B47789B6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 07:52:14.742070 :B47789B7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 07:52:14.742070 :B47789B8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 07:52:14.742070 :B47789B9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 07:52:14.742071 :B47789BA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 07:52:14.742555 :B47789BB: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-07-20 07:52:14.742611 :B47789C2: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-07-20 08:02:15.623037 :B47850D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 08:02:16.111941 :B4785134:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 08:02:16.107 2025-07-20 08:02:16.111943 :B4785135:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19168 pso_num=22 pso_serial#=44 2025-07-20 08:02:16.112085 :B4785136:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19168 osp_idx=0 osp_ver=424436 osp_pg=0 (spawn #403894) 2025-07-20 08:02:16.112085 :B4785137:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:02:16.112086 :B4785138:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=61 time=1679312001 2025-07-20 08:02:16.112086 :B4785139:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:02:16.112087 :B478513A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:02:16.112087 :B478513B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:02:16.112087 :B478513C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:02:16.112088 :B478513D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:02:16.112608 :B478513E: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-07-20 08:02:16.112656 :B4785146: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-07-20 08:12:17.039470 :B4792042:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-20 08:12:17.325376 :B479209C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-20 08:12:17.321 2025-07-20 08:12:17.325378 :B479209D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18552 pso_num=22 pso_serial#=45 2025-07-20 08:12:17.325524 :B479209E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18552 osp_idx=0 osp_ver=424437 osp_pg=0 (spawn #403917) 2025-07-20 08:12:17.325525 :B479209F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:12:17.325525 :B47920A0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=62 time=1679913220 2025-07-20 08:12:17.325526 :B47920A1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:12:17.325526 :B47920A2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:12:17.325526 :B47920A3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:12:17.325527 :B47920A4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:12:17.325527 :B47920A5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:12:17.326032 :B47920A6: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-07-20 08:12:17.326078 :B47920AC: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: 18552, W000)