Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250904221956\orcl_w000_15292_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:35061M/63366M, Ph+PgF:39923M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15292, image: ORACLE.EXE (W000) *** 2025-09-04 22:19:56.149 *** SESSION ID:(201.32813) 2025-09-04 22:19:56.149 *** 2025-09-04 22:19:56.149 Process diagnostic dump for ORACLE.EXE (W000), OS id=15292, pid: 25, proc_ser: 219, sid: 201, sess_ser: 32813 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=101 seq_num=102 snap_id=1 wait times: snap=1.176939 sec, exc=1.176939 sec, total=1.176939 sec wait times: max=5.000000 sec, heur=8 min 21 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.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=5.014743 sec, exc=5.014743 sec, total=5.014743 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=5.004621 sec, exc=5.004621 sec, total=5.004621 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=98 seq_num=99 snap_id=1 wait times: snap=5.014749 sec, exc=5.014749 sec, total=5.014749 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.008326 sec, exc=5.008326 sec, total=5.008326 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.000544 sec, exc=5.000544 sec, total=5.000544 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=93 seq_num=94 snap_id=1 wait times: snap=5.004422 sec, exc=5.004422 sec, total=5.004422 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.014730 sec, exc=5.014730 sec, total=5.014730 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.004624 sec, exc=5.004624 sec, total=5.004624 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 32813 --------------------------------------------------- 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, 22:17:56 - 22:19:56] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-04 22:19:56.149 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15292, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-04 20:35:22.702147 :C905AFE4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22684 pso_num=25 pso_serial#=205 2025-09-04 20:35:22.702265 :C905AFE5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22684 osp_idx=23 osp_ver=281483 osp_pg=0 (spawn #558951) 2025-09-04 20:35:22.702266 :C905AFE6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:35:22.702267 :C905AFE7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=72 time=1403774002 2025-09-04 20:35:22.702267 :C905AFE8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:35:22.702267 :C905AFE9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:35:22.702268 :C905AFEA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:35:22.702268 :C905AFEB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:35:22.702268 :C905AFEC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:35:22.702742 :C905AFED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:35:52.760785 :C905B9CB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:36:22.787176 :C905C394:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:36:22.776 2025-09-04 20:36:22.787178 :C905C395:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23744 pso_num=25 pso_serial#=206 2025-09-04 20:36:22.787305 :C905C396:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23744 osp_idx=23 osp_ver=281484 osp_pg=0 (spawn #558953) 2025-09-04 20:36:22.787306 :C905C397:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:36:22.787306 :C905C398:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=73 time=1403834080 2025-09-04 20:36:22.787307 :C905C399:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:36:22.787307 :C905C39A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:36:22.787308 :C905C39B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:36:22.787309 :C905C39C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:36:22.787309 :C905C39D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:36:22.787782 :C905C39E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:36:52.866531 :C905CE51:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:37:22.869509 :C905D806:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:37:22.857 2025-09-04 20:37:22.869512 :C905D807:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23172 pso_num=25 pso_serial#=207 2025-09-04 20:37:22.869631 :C905D808:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23172 osp_idx=23 osp_ver=281485 osp_pg=0 (spawn #558955) 2025-09-04 20:37:22.869631 :C905D809:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:37:22.869632 :C905D80A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=74 time=1403894158 2025-09-04 20:37:22.869633 :C905D80B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:37:22.869633 :C905D80C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:37:22.869634 :C905D80D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:37:22.869634 :C905D80E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:37:22.869635 :C905D80F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:37:22.870162 :C905D810:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:37:52.986907 :C905E1D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:38:22.943009 :C905EBAA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:38:22.930 2025-09-04 20:38:22.943011 :C905EBAB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21564 pso_num=25 pso_serial#=208 2025-09-04 20:38:22.943148 :C905EBAC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21564 osp_idx=23 osp_ver=281486 osp_pg=0 (spawn #558957) 2025-09-04 20:38:22.943148 :C905EBAD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:38:22.943149 :C905EBAE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=75 time=1403954237 2025-09-04 20:38:22.943149 :C905EBAF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:38:22.943149 :C905EBB0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:38:22.943150 :C905EBB1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:38:22.943150 :C905EBB2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:38:22.943150 :C905EBB3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:38:22.943663 :C905EBB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:38:53.023900 :C905F5CD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:39:23.039575 :C905FFB4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:39:23.028 2025-09-04 20:39:23.039577 :C905FFB5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23420 pso_num=25 pso_serial#=209 2025-09-04 20:39:23.039721 :C905FFB6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23420 osp_idx=23 osp_ver=281487 osp_pg=0 (spawn #558960) 2025-09-04 20:39:23.039721 :C905FFB7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:39:23.039722 :C905FFB8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=76 time=1404014330 2025-09-04 20:39:23.039722 :C905FFB9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:39:23.039722 :C905FFBA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:39:23.039723 :C905FFBB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:39:23.039723 :C905FFBC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:39:23.039723 :C905FFBD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:39:23.040208 :C905FFBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:39:53.105831 :C90609D3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:40:23.122449 :C906143F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:40:23.111 2025-09-04 20:40:23.122451 :C9061440:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23748 pso_num=25 pso_serial#=210 2025-09-04 20:40:23.122590 :C9061441:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23748 osp_idx=23 osp_ver=281488 osp_pg=0 (spawn #558962) 2025-09-04 20:40:23.122590 :C9061442:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:40:23.122591 :C9061443:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=77 time=1404074424 2025-09-04 20:40:23.122591 :C9061444:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:40:23.122591 :C9061445:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:40:23.122592 :C9061446:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:40:23.122592 :C9061447:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:40:23.122592 :C9061448:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:40:23.123063 :C9061449:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:40:53.181428 :C9061E22:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:41:23.257211 :C9062848:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:41:23.252 2025-09-04 20:41:23.257212 :C9062849:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23668 pso_num=25 pso_serial#=211 2025-09-04 20:41:23.257348 :C906284A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23668 osp_idx=23 osp_ver=281489 osp_pg=0 (spawn #558965) 2025-09-04 20:41:23.257349 :C906284B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:41:23.257349 :C906284C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=78 time=1404134565 2025-09-04 20:41:23.257350 :C906284D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:41:23.257350 :C906284E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:41:23.257350 :C906284F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:41:23.257351 :C9062850:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:41:23.257351 :C9062851:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:41:23.257920 :C9062852:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:41:23.257969 :C9062859:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 20:41:38.275545 :C9062D95:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 20:51:24.171259 :C906EFC2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 20:51:24.582176 :C906F082:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 20:51:24.577 2025-09-04 20:51:24.582177 :C906F083:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17500 pso_num=25 pso_serial#=212 2025-09-04 20:51:24.582311 :C906F084:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17500 osp_idx=23 osp_ver=281490 osp_pg=0 (spawn #558989) 2025-09-04 20:51:24.582311 :C906F085:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 20:51:24.582312 :C906F086:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=79 time=1404735893 2025-09-04 20:51:24.582312 :C906F087:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 20:51:24.582312 :C906F088:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 20:51:24.582313 :C906F089:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 20:51:24.582313 :C906F08A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 20:51:24.582313 :C906F08B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 20:51:24.582800 :C906F08C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 20:51:24.582842 :C906F094:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 20:55:06.626860 :C90739DD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-04 21:01:25.782116 :C907BB9A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-04 21:11:26.632974 :C9088ADA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 21:11:27.100699 :C9088B3F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 21:11:27.095 2025-09-04 21:11:27.100701 :C9088B40:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22888 pso_num=25 pso_serial#=213 2025-09-04 21:11:27.100839 :C9088B41:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22888 osp_idx=23 osp_ver=281491 osp_pg=0 (spawn #559036) 2025-09-04 21:11:27.100840 :C9088B42:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 21:11:27.100840 :C9088B43:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=80 time=1405938408 2025-09-04 21:11:27.100841 :C9088B44:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 21:11:27.100841 :C9088B45:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 21:11:27.100841 :C9088B46:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 21:11:27.100842 :C9088B47:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 21:11:27.100842 :C9088B48:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 21:11:27.101326 :C9088B49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 21:11:27.101371 :C9088B52:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 21:21:28.004860 :C9095749:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 21:21:28.384554 :C90957AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 21:21:28.379 2025-09-04 21:21:28.384556 :C90957AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20372 pso_num=25 pso_serial#=214 2025-09-04 21:21:28.384699 :C90957AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20372 osp_idx=23 osp_ver=281492 osp_pg=0 (spawn #559059) 2025-09-04 21:21:28.384700 :C90957AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 21:21:28.384701 :C90957AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=81 time=1406539690 2025-09-04 21:21:28.384701 :C90957B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 21:21:28.384701 :C90957B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 21:21:28.384703 :C90957B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 21:21:28.384704 :C90957B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 21:21:28.384704 :C90957B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 21:21:28.385223 :C90957B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 21:21:28.385283 :C90957BF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 21:31:29.223059 :C90A20A6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 21:31:29.618573 :C90A2106:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 21:31:29.613 2025-09-04 21:31:29.618576 :C90A2107:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14024 pso_num=25 pso_serial#=215 2025-09-04 21:31:29.618720 :C90A2108:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14024 osp_idx=23 osp_ver=281493 osp_pg=0 (spawn #559082) 2025-09-04 21:31:29.618721 :C90A2109:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 21:31:29.618721 :C90A210A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=82 time=1407140924 2025-09-04 21:31:29.618722 :C90A210B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 21:31:29.618722 :C90A210C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 21:31:29.618722 :C90A210D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 21:31:29.618723 :C90A210E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 21:31:29.618723 :C90A210F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 21:31:29.619216 :C90A2110:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 21:31:29.619264 :C90A2118:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 21:41:30.359099 :C90AEA84:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 21:41:30.779803 :C90AEAE8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 21:41:30.775 2025-09-04 21:41:30.779806 :C90AEAE9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21996 pso_num=25 pso_serial#=216 2025-09-04 21:41:30.779976 :C90AEAEA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21996 osp_idx=23 osp_ver=281494 osp_pg=0 (spawn #559104) 2025-09-04 21:41:30.779977 :C90AEAEB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 21:41:30.779978 :C90AEAEC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=83 time=1407742080 2025-09-04 21:41:30.779978 :C90AEAED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 21:41:30.779978 :C90AEAEE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 21:41:30.779979 :C90AEAEF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 21:41:30.779979 :C90AEAF0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 21:41:30.779979 :C90AEAF1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 21:41:30.780541 :C90AEAF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 21:41:30.780590 :C90AEAFC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 21:51:31.517653 :C90BB476:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 21:51:31.960335 :C90BB4D0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 21:51:31.955 2025-09-04 21:51:31.960338 :C90BB4D1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20028 pso_num=25 pso_serial#=217 2025-09-04 21:51:31.960488 :C90BB4D2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20028 osp_idx=23 osp_ver=281495 osp_pg=0 (spawn #559127) 2025-09-04 21:51:31.960489 :C90BB4D3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 21:51:31.960489 :C90BB4D4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=84 time=1408343268 2025-09-04 21:51:31.960489 :C90BB4D5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 21:51:31.960490 :C90BB4D6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 21:51:31.960490 :C90BB4D7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 21:51:31.960491 :C90BB4D8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 21:51:31.960491 :C90BB4D9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 21:51:31.961011 :C90BB4DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 21:51:31.961053 :C90BB4E1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 22:01:32.743318 :C90C8AD1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 22:01:33.008343 :C90C8AF4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 22:01:33.003 2025-09-04 22:01:33.008345 :C90C8AF5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18624 pso_num=25 pso_serial#=218 2025-09-04 22:01:33.008483 :C90C8AF6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18624 osp_idx=23 osp_ver=281496 osp_pg=0 (spawn #559158) 2025-09-04 22:01:33.008484 :C90C8AF7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 22:01:33.008484 :C90C8AF8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=1408944315 2025-09-04 22:01:33.008485 :C90C8AF9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 22:01:33.008485 :C90C8AFA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 22:01:33.008485 :C90C8AFB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 22:01:33.008486 :C90C8AFC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 22:01:33.008486 :C90C8AFD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 22:01:33.008990 :C90C8AFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 22:01:33.009038 :C90C8B05:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-04 22:11:33.958942 :C90D657A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-04 22:11:34.213776 :C90D659E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-04 22:11:34.209 2025-09-04 22:11:34.213778 :C90D659F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15292 pso_num=25 pso_serial#=219 2025-09-04 22:11:34.213926 :C90D65A0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15292 osp_idx=23 osp_ver=281497 osp_pg=0 (spawn #559181) 2025-09-04 22:11:34.213926 :C90D65A1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 22:11:34.213927 :C90D65A2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=86 time=1409545518 2025-09-04 22:11:34.213927 :C90D65A3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 22:11:34.213928 :C90D65A4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 22:11:34.213928 :C90D65A5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 22:11:34.213928 :C90D65A6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 22:11:34.213929 :C90D65A7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 22:11:34.214436 :C90D65A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 22:11:34.214484 :C90D65B0: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: 15292, W000)