Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250608180847\orcl_w000_15688_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:36787M/63366M, Ph+PgF:43381M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15688, image: ORACLE.EXE (W000) *** 2025-06-08 18:08:47.873 *** SESSION ID:(201.10029) 2025-06-08 18:08:47.873 *** 2025-06-08 18:08:47.873 Process diagnostic dump for ORACLE.EXE (W000), OS id=15688, pid: 25, proc_ser: 100, sid: 201, sess_ser: 10029 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=4.694821 sec, exc=4.694821 sec, total=4.694821 sec wait times: max=5.000000 sec, heur=2 min 34 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=30 seq_num=31 snap_id=1 wait times: snap=5.008725 sec, exc=5.008725 sec, total=5.008725 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: 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.014764 sec, exc=5.014764 sec, total=5.014764 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=28 seq_num=29 snap_id=1 wait times: snap=5.014719 sec, exc=5.014719 sec, total=5.014719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.007250 sec, exc=5.007250 sec, total=5.007250 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: 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.001294 sec, exc=5.001294 sec, total=5.001294 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=25 seq_num=26 snap_id=1 wait times: snap=5.000461 sec, exc=5.000461 sec, total=5.000461 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.002502 sec, exc=5.002502 sec, total=5.002502 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.003232 sec, exc=5.003232 sec, total=5.003232 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=22 seq_num=23 snap_id=1 wait times: snap=5.004641 sec, exc=5.004641 sec, total=5.004641 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=5.004652 sec, exc=5.004652 sec, total=5.004652 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 201 serial 10029 --------------------------------------------------- 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, 18:06:47 - 18:08:47] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-08 18:08:47.873 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15688, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-08 15:35:55.565873 :A201C8FD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 15:35:55.566398 :A201C8FE: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-06-08 15:35:55.566444 :A201C905: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-06-08 15:45:56.371984 :A2028BFE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 15:45:56.798664 :A2028C37:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 15:45:56.793 2025-06-08 15:45:56.798667 :A2028C38:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15092 pso_num=25 pso_serial#=87 2025-06-08 15:45:56.798809 :A2028C39:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15092 osp_idx=23 osp_ver=151414 osp_pg=0 (spawn #264948) 2025-06-08 15:45:56.798810 :A2028C3A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 15:45:56.798810 :A2028C3B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=79 time=2373299985 2025-06-08 15:45:56.798810 :A2028C3C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 15:45:56.798811 :A2028C3D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 15:45:56.798811 :A2028C3E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 15:45:56.798811 :A2028C3F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 15:45:56.798812 :A2028C40:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 15:45:56.799319 :A2028C41: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-06-08 15:45:56.799364 :A2028C48: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-06-08 15:55:57.613609 :A2034F64:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 15:55:57.993275 :A2034FA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 15:55:57.989 2025-06-08 15:55:57.993277 :A2034FAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12944 pso_num=25 pso_serial#=88 2025-06-08 15:55:57.993413 :A2034FAB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12944 osp_idx=23 osp_ver=151415 osp_pg=0 (spawn #264970) 2025-06-08 15:55:57.993413 :A2034FAC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 15:55:57.993414 :A2034FAD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=80 time=2373901188 2025-06-08 15:55:57.993414 :A2034FAE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 15:55:57.993415 :A2034FAF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 15:55:57.993415 :A2034FB0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 15:55:57.993416 :A2034FB1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 15:55:57.993416 :A2034FB2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 15:55:57.993942 :A2034FB3: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-06-08 15:55:57.993998 :A2034FBD: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-06-08 16:05:58.773801 :A2041C1C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 16:05:59.200461 :A2041C55:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 16:05:59.195 2025-06-08 16:05:59.200463 :A2041C56:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16252 pso_num=25 pso_serial#=89 2025-06-08 16:05:59.200606 :A2041C57:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16252 osp_idx=23 osp_ver=151416 osp_pg=0 (spawn #264997) 2025-06-08 16:05:59.200607 :A2041C58:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 16:05:59.200607 :A2041C59:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=81 time=2374502391 2025-06-08 16:05:59.200608 :A2041C5A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 16:05:59.200608 :A2041C5B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 16:05:59.200608 :A2041C5C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 16:05:59.200609 :A2041C5D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 16:05:59.200609 :A2041C5E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 16:05:59.201107 :A2041C5F: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-06-08 16:05:59.201153 :A2041C66: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-06-08 16:15:59.857308 :A204E26F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 16:16:00.252572 :A204E2A6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 16:16:00.248 2025-06-08 16:16:00.252574 :A204E2A7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14196 pso_num=25 pso_serial#=90 2025-06-08 16:16:00.252718 :A204E2A8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14196 osp_idx=23 osp_ver=151417 osp_pg=0 (spawn #265020) 2025-06-08 16:16:00.252718 :A204E2A9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 16:16:00.252719 :A204E2AA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=82 time=2375103438 2025-06-08 16:16:00.252719 :A204E2AB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 16:16:00.252720 :A204E2AC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 16:16:00.252720 :A204E2AD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 16:16:00.252720 :A204E2AE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 16:16:00.252721 :A204E2AF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 16:16:00.253214 :A204E2B0: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-06-08 16:16:00.253261 :A204E2B7: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-06-08 16:26:01.095772 :A205A583:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 16:26:01.381768 :A205A5CD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 16:26:01.376 2025-06-08 16:26:01.381770 :A205A5CE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16000 pso_num=25 pso_serial#=91 2025-06-08 16:26:01.381909 :A205A5CF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16000 osp_idx=23 osp_ver=151418 osp_pg=0 (spawn #265042) 2025-06-08 16:26:01.381910 :A205A5D0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 16:26:01.381910 :A205A5D1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=83 time=2375704579 2025-06-08 16:26:01.381911 :A205A5D2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 16:26:01.381911 :A205A5D3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 16:26:01.381911 :A205A5D4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 16:26:01.381912 :A205A5D5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 16:26:01.381912 :A205A5D6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 16:26:01.382400 :A205A5D7: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-06-08 16:26:01.382449 :A205A5DE: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-06-08 16:36:02.341526 :A2066A33:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 16:36:02.611852 :A2066A6E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 16:36:02.607 2025-06-08 16:36:02.611854 :A2066A6F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15964 pso_num=25 pso_serial#=92 2025-06-08 16:36:02.611999 :A2066A70:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15964 osp_idx=23 osp_ver=151419 osp_pg=0 (spawn #265065) 2025-06-08 16:36:02.611999 :A2066A71:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 16:36:02.612000 :A2066A72:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=84 time=2376305797 2025-06-08 16:36:02.612000 :A2066A73:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 16:36:02.612000 :A2066A74:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 16:36:02.612001 :A2066A75:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 16:36:02.612001 :A2066A76:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 16:36:02.612001 :A2066A77:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 16:36:02.612493 :A2066A78: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-06-08 16:36:02.612540 :A2066A80: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-06-08 16:45:03.252941 :A20719DA: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-06-08 16:46:03.756488 :A2072D2C: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-06-08 16:56:04.566449 :A207F078:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 16:56:04.853401 :A207F0B1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 16:56:04.849 2025-06-08 16:56:04.853403 :A207F0B2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15968 pso_num=25 pso_serial#=93 2025-06-08 16:56:04.853562 :A207F0B3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15968 osp_idx=23 osp_ver=151420 osp_pg=0 (spawn #265109) 2025-06-08 16:56:04.853562 :A207F0B4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 16:56:04.853563 :A207F0B5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=2377508047 2025-06-08 16:56:04.853563 :A207F0B6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 16:56:04.853563 :A207F0B7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 16:56:04.853564 :A207F0B8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 16:56:04.853564 :A207F0B9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 16:56:04.853564 :A207F0BA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 16:56:04.854074 :A207F0BB: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-06-08 16:56:04.854123 :A207F0C3: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-06-08 17:06:05.682084 :A208BC6A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 17:06:05.952276 :A208BCB1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 17:06:05.948 2025-06-08 17:06:05.952278 :A208BCB2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14820 pso_num=25 pso_serial#=94 2025-06-08 17:06:05.952411 :A208BCB3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14820 osp_idx=23 osp_ver=151421 osp_pg=0 (spawn #265135) 2025-06-08 17:06:05.952411 :A208BCB4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 17:06:05.952412 :A208BCB5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=86 time=2378109141 2025-06-08 17:06:05.952412 :A208BCB6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 17:06:05.952412 :A208BCB7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 17:06:05.952412 :A208BCB8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 17:06:05.952413 :A208BCB9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 17:06:05.952413 :A208BCBA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 17:06:05.952895 :A208BCBB: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-06-08 17:06:05.952954 :A208BCC1: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-06-08 17:16:06.793184 :A20982EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 17:16:07.094797 :A209834D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 17:16:07.090 2025-06-08 17:16:07.094800 :A209834E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14012 pso_num=25 pso_serial#=95 2025-06-08 17:16:07.094937 :A209834F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14012 osp_idx=23 osp_ver=151422 osp_pg=0 (spawn #265158) 2025-06-08 17:16:07.094938 :A2098350:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 17:16:07.094939 :A2098351:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=87 time=2378710282 2025-06-08 17:16:07.094939 :A2098352:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 17:16:07.094940 :A2098353:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 17:16:07.094940 :A2098354:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 17:16:07.094941 :A2098355:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 17:16:07.094941 :A2098356:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 17:16:07.095440 :A2098357: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-06-08 17:16:07.095484 :A209835F: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-06-08 17:26:08.035878 :A20A4634:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 17:26:08.321826 :A20A46B3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 17:26:08.317 2025-06-08 17:26:08.321828 :A20A46B4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14524 pso_num=25 pso_serial#=96 2025-06-08 17:26:08.321974 :A20A46B5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14524 osp_idx=23 osp_ver=151423 osp_pg=0 (spawn #265180) 2025-06-08 17:26:08.321975 :A20A46B6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 17:26:08.321976 :A20A46B7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=2379311516 2025-06-08 17:26:08.321977 :A20A46B8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 17:26:08.321977 :A20A46B9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 17:26:08.321978 :A20A46BA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 17:26:08.321978 :A20A46BB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 17:26:08.321978 :A20A46BC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 17:26:08.322493 :A20A46BD: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-06-08 17:26:08.322538 :A20A46C5: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-06-08 17:36:09.159802 :A20B0954:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 17:36:09.414939 :A20B09A6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 17:36:09.410 2025-06-08 17:36:09.414941 :A20B09A7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13760 pso_num=25 pso_serial#=97 2025-06-08 17:36:09.415094 :A20B09A8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13760 osp_idx=23 osp_ver=151424 osp_pg=0 (spawn #265203) 2025-06-08 17:36:09.415095 :A20B09A9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 17:36:09.415095 :A20B09AA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=2379912610 2025-06-08 17:36:09.415096 :A20B09AB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 17:36:09.415096 :A20B09AC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 17:36:09.415096 :A20B09AD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 17:36:09.415097 :A20B09AE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 17:36:09.415097 :A20B09AF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 17:36:09.415643 :A20B09B0: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-06-08 17:36:09.415695 :A20B09B6: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-06-08 17:46:10.260491 :A20BCCB9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 17:46:10.608952 :A20BCD10:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 17:46:10.609 2025-06-08 17:46:10.608954 :A20BCD11:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14976 pso_num=25 pso_serial#=98 2025-06-08 17:46:10.609087 :A20BCD12:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14976 osp_idx=23 osp_ver=151425 osp_pg=0 (spawn #265226) 2025-06-08 17:46:10.609088 :A20BCD13:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 17:46:10.609088 :A20BCD14:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=2380513797 2025-06-08 17:46:10.609088 :A20BCD15:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 17:46:10.609089 :A20BCD16:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 17:46:10.609089 :A20BCD17:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 17:46:10.609089 :A20BCD18:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 17:46:10.609090 :A20BCD19:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 17:46:10.609581 :A20BCD1A: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-06-08 17:46:10.609624 :A20BCD21: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-06-08 17:56:11.392592 :A20C8F9F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 17:56:11.834832 :A20C8FF0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 17:56:11.830 2025-06-08 17:56:11.834835 :A20C8FF1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15964 pso_num=25 pso_serial#=99 2025-06-08 17:56:11.834969 :A20C8FF2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15964 osp_idx=23 osp_ver=151426 osp_pg=0 (spawn #265248) 2025-06-08 17:56:11.834970 :A20C8FF3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 17:56:11.834970 :A20C8FF4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=2381115032 2025-06-08 17:56:11.834970 :A20C8FF5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 17:56:11.834971 :A20C8FF6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 17:56:11.834971 :A20C8FF7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 17:56:11.834971 :A20C8FF8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 17:56:11.834971 :A20C8FF9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 17:56:11.835463 :A20C8FFA: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-06-08 17:56:11.835507 :A20C9001: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-06-08 18:06:12.548218 :A20D5D18:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-08 18:06:12.943597 :A20D5D62:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 18:06:12.938 2025-06-08 18:06:12.943599 :A20D5D63:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15688 pso_num=25 pso_serial#=100 2025-06-08 18:06:12.943746 :A20D5D64:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15688 osp_idx=23 osp_ver=151427 osp_pg=0 (spawn #265274) 2025-06-08 18:06:12.943746 :A20D5D65:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 18:06:12.943747 :A20D5D66:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=2381716141 2025-06-08 18:06:12.943747 :A20D5D67:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 18:06:12.943748 :A20D5D68:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 18:06:12.943748 :A20D5D69:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 18:06:12.943748 :A20D5D6A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 18:06:12.943749 :A20D5D6B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 18:06:12.944247 :A20D5D6C: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-06-08 18:06:12.944294 :A20D5D73: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: 15688, W000)