Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726140413\orcl_w000_16940_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:36029M/63366M, Ph+PgF:41834M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 16940, image: ORACLE.EXE (W000) *** 2025-07-26 14:04:13.201 *** SESSION ID:(209.5199) 2025-07-26 14:04:13.201 *** 2025-07-26 14:04:13.201 Process diagnostic dump for ORACLE.EXE (W000), OS id=16940, pid: 26, proc_ser: 141, sid: 209, sess_ser: 5199 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=4.341805 sec, exc=4.341805 sec, total=4.341805 sec wait times: max=5.000000 sec, heur=2 min 59 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.000027 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.009730 sec, exc=5.009730 sec, total=5.009730 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=34 seq_num=35 snap_id=1 wait times: snap=5.002133 sec, exc=5.002133 sec, total=5.002133 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.001407 sec, exc=5.001407 sec, total=5.001407 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.000418 sec, exc=5.000418 sec, total=5.000418 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.013934 sec, exc=5.013934 sec, total=5.013934 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.003409 sec, exc=5.003409 sec, total=5.003409 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=29 seq_num=30 snap_id=1 wait times: snap=5.014713 sec, exc=5.014713 sec, total=5.014713 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=28 seq_num=29 snap_id=1 wait times: snap=5.010157 sec, exc=5.010157 sec, total=5.010157 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.014709 sec, exc=5.014709 sec, total=5.014709 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.007230 sec, exc=5.007230 sec, total=5.007230 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time Sampled Session History of session 209 serial 5199 --------------------------------------------------- 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, 14:02:13 - 14:04:13] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 14:04:13.201 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 16940, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 11:40:57.807635 :B7336636:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=115 time=2210833704 2025-07-26 11:40:57.807635 :B7336637:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 11:40:57.807636 :B7336638:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 11:40:57.807636 :B7336639:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 11:40:57.807636 :B733663A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 11:40:57.807637 :B733663B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 11:40:57.808138 :B733663C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 11:40:57.808182 :B7336643:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 11:50:58.594312 :B7342A3E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 11:50:59.098973 :B7342AA4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 11:50:59.094 2025-07-26 11:50:59.098975 :B7342AA5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=280 pso_num=26 pso_serial#=128 2025-07-26 11:50:59.099114 :B7342AA6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=280 osp_idx=24 osp_ver=688883 osp_pg=0 (spawn #424420) 2025-07-26 11:50:59.099115 :B7342AA7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 11:50:59.099115 :B7342AA8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=116 time=2211434986 2025-07-26 11:50:59.099116 :B7342AA9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 11:50:59.099116 :B7342AAA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 11:50:59.099117 :B7342AAB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 11:50:59.099117 :B7342AAC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 11:50:59.099118 :B7342AAD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 11:50:59.099608 :B7342AAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 11:50:59.099652 :B7342AB6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:00:59.804379 :B734EFF0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 12:01:00.231076 :B734F044:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 12:01:00.226 2025-07-26 12:01:00.231078 :B734F045:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18756 pso_num=26 pso_serial#=129 2025-07-26 12:01:00.231223 :B734F046:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18756 osp_idx=24 osp_ver=688884 osp_pg=0 (spawn #424445) 2025-07-26 12:01:00.231224 :B734F047:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 12:01:00.231224 :B734F048:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=117 time=2212036126 2025-07-26 12:01:00.231224 :B734F049:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 12:01:00.231225 :B734F04A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 12:01:00.231225 :B734F04B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 12:01:00.231225 :B734F04C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 12:01:00.231226 :B734F04D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 12:01:00.231723 :B734F04E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 12:01:00.231767 :B734F056:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:04:50.351854 :B7353A64:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:11:01.430076 :B735BCBD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:11:01.430110 :B735BCC2:db_trace:ksl2.c@2477:kslwtbctx(): [10005:26:209] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-07-26 12:11:01.430153 :B735BCC7:db_trace:ksl2.c@2611:kslwtectx(): [10005:26:209] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1 2025-07-26 12:11:01.430154 :B735BCC8:db_trace:ksl2.c@2619:kslwtectx(): [10005:26:209] KSL WAIT END wait times (usecs) - snap=41, exc=41, tot=41 2025-07-26 12:21:02.270434 :B7367F45:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 12:21:02.619098 :B7367F9E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 12:21:02.614 2025-07-26 12:21:02.619100 :B7367F9F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11148 pso_num=26 pso_serial#=130 2025-07-26 12:21:02.619235 :B7367FA0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11148 osp_idx=24 osp_ver=688885 osp_pg=0 (spawn #424490) 2025-07-26 12:21:02.619238 :B7367FA1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 12:21:02.619238 :B7367FA2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=118 time=2213238517 2025-07-26 12:21:02.619238 :B7367FA3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 12:21:02.619239 :B7367FA4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 12:21:02.619239 :B7367FA5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 12:21:02.619239 :B7367FA6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 12:21:02.619240 :B7367FA7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 12:21:02.619729 :B7367FA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 12:21:02.619780 :B7367FAE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:31:03.341588 :B73743E8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 12:31:03.759505 :B73743F7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 12:31:03.748 2025-07-26 12:31:03.759507 :B73743F8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12740 pso_num=26 pso_serial#=131 2025-07-26 12:31:03.759649 :B73743F9:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12740 (legacy spawn) 2025-07-26 12:31:03.766381 :B73743FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 12:31:03.766550 :B7374403:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 12:31:03.767358 :B7374404:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 12:31:03.799788 :B7374455:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 12:31:03.794 2025-07-26 12:31:03.799790 :B7374456:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19060 pso_num=26 pso_serial#=132 2025-07-26 12:31:03.799939 :B7374457:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19060 osp_idx=24 osp_ver=688887 osp_pg=0 (spawn #424513) 2025-07-26 12:31:03.799940 :B7374458:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 12:31:03.799941 :B7374459:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=119 time=2213839689 2025-07-26 12:31:03.799941 :B737445A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 12:31:03.799941 :B737445B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 12:31:03.799942 :B737445C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 12:31:03.799942 :B737445D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 12:31:03.799942 :B737445E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 12:31:03.800471 :B737445F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 12:31:03.800517 :B7374466:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:41:04.529905 :B73807EF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 12:41:04.878496 :B7380841:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 12:41:04.873 2025-07-26 12:41:04.878498 :B7380842:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1216 pso_num=26 pso_serial#=133 2025-07-26 12:41:04.878646 :B7380843:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=1216 osp_idx=24 osp_ver=688888 osp_pg=0 (spawn #424536) 2025-07-26 12:41:04.878646 :B7380844:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 12:41:04.878647 :B7380845:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=120 time=2214440767 2025-07-26 12:41:04.878647 :B7380846:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 12:41:04.878647 :B7380847:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 12:41:04.878648 :B7380848:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 12:41:04.878648 :B7380849:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 12:41:04.878648 :B738084A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 12:41:04.879167 :B738084B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 12:41:04.879215 :B7380853:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 12:51:05.644180 :B738CBAD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 12:51:06.070828 :B738CC05:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 12:51:06.065 2025-07-26 12:51:06.070830 :B738CC06:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7652 pso_num=26 pso_serial#=134 2025-07-26 12:51:06.070969 :B738CC07:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7652 osp_idx=24 osp_ver=688889 osp_pg=0 (spawn #424558) 2025-07-26 12:51:06.070970 :B738CC08:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 12:51:06.070971 :B738CC09:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=121 time=2215041970 2025-07-26 12:51:06.070971 :B738CC0A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 12:51:06.070972 :B738CC0B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 12:51:06.070972 :B738CC0C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 12:51:06.070972 :B738CC0D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 12:51:06.070972 :B738CC0E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 12:51:06.071471 :B738CC0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 12:51:06.071520 :B738CC18:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 13:01:06.796792 :B73991B5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 13:01:07.223468 :B7399217:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 13:01:07.218 2025-07-26 13:01:07.223469 :B7399218:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14852 pso_num=26 pso_serial#=135 2025-07-26 13:01:07.223603 :B7399219:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14852 osp_idx=24 osp_ver=688890 osp_pg=0 (spawn #424583) 2025-07-26 13:01:07.223604 :B739921A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 13:01:07.223605 :B739921B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=122 time=2215643111 2025-07-26 13:01:07.223605 :B739921C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 13:01:07.223605 :B739921D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 13:01:07.223606 :B739921E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 13:01:07.223606 :B739921F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 13:01:07.223606 :B7399220:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 13:01:07.224102 :B7399221:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 13:01:07.224152 :B7399229:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 13:11:08.043547 :B73A5E41:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 13:11:08.313748 :B73A5EA2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 13:11:08.309 2025-07-26 13:11:08.313750 :B73A5EA3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7568 pso_num=26 pso_serial#=136 2025-07-26 13:11:08.313885 :B73A5EA4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7568 osp_idx=24 osp_ver=688891 osp_pg=0 (spawn #424607) 2025-07-26 13:11:08.313885 :B73A5EA5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 13:11:08.313886 :B73A5EA6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=123 time=2216244204 2025-07-26 13:11:08.313887 :B73A5EA7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 13:11:08.313887 :B73A5EA8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 13:11:08.313888 :B73A5EA9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 13:11:08.313889 :B73A5EAA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 13:11:08.313889 :B73A5EAB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 13:11:08.314381 :B73A5EAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 13:11:08.314427 :B73A5EB6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 13:21:09.120579 :B73B2147:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 13:21:09.375312 :B73B21A6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 13:21:09.370 2025-07-26 13:21:09.375314 :B73B21A7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17340 pso_num=26 pso_serial#=137 2025-07-26 13:21:09.375455 :B73B21A8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17340 osp_idx=24 osp_ver=688892 osp_pg=0 (spawn #424629) 2025-07-26 13:21:09.375456 :B73B21A9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 13:21:09.375456 :B73B21AA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=124 time=2216845267 2025-07-26 13:21:09.375456 :B73B21AB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 13:21:09.375457 :B73B21AC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 13:21:09.375457 :B73B21AD:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 13:21:09.375457 :B73B21AE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 13:21:09.375458 :B73B21AF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 13:21:09.375958 :B73B21B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 13:21:09.376004 :B73B21B7:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 13:31:10.219608 :B73BE696:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 13:31:10.516147 :B73BE6F0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 13:31:10.511 2025-07-26 13:31:10.516149 :B73BE6F1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18456 pso_num=26 pso_serial#=138 2025-07-26 13:31:10.516294 :B73BE6F2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18456 osp_idx=24 osp_ver=688893 osp_pg=0 (spawn #424652) 2025-07-26 13:31:10.516295 :B73BE6F3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 13:31:10.516296 :B73BE6F4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=125 time=2217446408 2025-07-26 13:31:10.516296 :B73BE6F5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 13:31:10.516297 :B73BE6F6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 13:31:10.516297 :B73BE6F7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 13:31:10.516298 :B73BE6F8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 13:31:10.516298 :B73BE6F9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 13:31:10.516879 :B73BE6FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 13:31:10.516950 :B73BE704:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 13:41:11.307050 :B73CAA93:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 13:41:11.548217 :B73CAAE6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 13:41:11.543 2025-07-26 13:41:11.548219 :B73CAAE7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12108 pso_num=26 pso_serial#=139 2025-07-26 13:41:11.548361 :B73CAAE8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12108 osp_idx=24 osp_ver=688894 osp_pg=0 (spawn #424675) 2025-07-26 13:41:11.548362 :B73CAAE9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 13:41:11.548362 :B73CAAEA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=126 time=2218047439 2025-07-26 13:41:11.548362 :B73CAAEB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 13:41:11.548363 :B73CAAEC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 13:41:11.548363 :B73CAAED:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 13:41:11.548363 :B73CAAEE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 13:41:11.548363 :B73CAAEF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 13:41:11.548846 :B73CAAF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 13:41:11.548900 :B73CAAFA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 13:51:12.260380 :B73D6DE1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 13:51:12.561917 :B73D6E3D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 13:51:12.557 2025-07-26 13:51:12.561919 :B73D6E3E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18696 pso_num=26 pso_serial#=140 2025-07-26 13:51:12.562058 :B73D6E3F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18696 osp_idx=24 osp_ver=688895 osp_pg=0 (spawn #424697) 2025-07-26 13:51:12.562059 :B73D6E40:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 13:51:12.562060 :B73D6E41:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=127 time=2218648454 2025-07-26 13:51:12.562060 :B73D6E42:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 13:51:12.562060 :B73D6E43:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 13:51:12.562061 :B73D6E44:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 13:51:12.562061 :B73D6E45:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 13:51:12.562061 :B73D6E46:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 13:51:12.562558 :B73D6E47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 13:51:12.562602 :B73D6E4F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 14:01:13.282331 :B73E3363:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 14:01:13.625491 :B73E33B8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 14:01:13.620 2025-07-26 14:01:13.625493 :B73E33B9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16940 pso_num=26 pso_serial#=141 2025-07-26 14:01:13.625642 :B73E33BA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16940 osp_idx=24 osp_ver=688896 osp_pg=0 (spawn #424722) 2025-07-26 14:01:13.625643 :B73E33BB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 14:01:13.625643 :B73E33BC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=128 time=2219249517 2025-07-26 14:01:13.625644 :B73E33BD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 14:01:13.625644 :B73E33BE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 14:01:13.625644 :B73E33BF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 14:01:13.625645 :B73E33C0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 14:01:13.625645 :B73E33C1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 14:01:13.626153 :B73E33C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 14:01:13.626210 :B73E33C8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 16940, W000)