Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250828220009\orcl_w000_23248_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:35124M/63366M, Ph+PgF:40119M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23248, image: ORACLE.EXE (W000) *** 2025-08-28 22:00:09.291 *** SESSION ID:(201.57809) 2025-08-28 22:00:09.291 *** 2025-08-28 22:00:09.291 Process diagnostic dump for ORACLE.EXE (W000), OS id=23248, pid: 25, proc_ser: 198, sid: 201, sess_ser: 57809 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=3.392139 sec, exc=3.392139 sec, total=3.392139 sec wait times: max=5.000000 sec, heur=8 min 49 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=105 seq_num=106 snap_id=1 wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=104 seq_num=105 snap_id=1 wait times: snap=5.008776 sec, exc=5.008776 sec, total=5.008776 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=103 seq_num=104 snap_id=1 wait times: snap=5.001145 sec, exc=5.001145 sec, total=5.001145 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=102 seq_num=103 snap_id=1 wait times: snap=5.012983 sec, exc=5.012983 sec, total=5.012983 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=101 seq_num=102 snap_id=1 wait times: snap=5.010787 sec, exc=5.010787 sec, total=5.010787 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: 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.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: 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.004631 sec, exc=5.004631 sec, total=5.004631 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=98 seq_num=99 snap_id=1 wait times: snap=5.002235 sec, exc=5.002235 sec, total=5.002235 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 8: 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.005156 sec, exc=5.005156 sec, total=5.005156 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 9: 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.003368 sec, exc=5.003368 sec, total=5.003368 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 201 serial 57809 --------------------------------------------------- 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, 21:58:09 - 22:00:09] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-28 22:00:09.291 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23248, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-28 21:18:16.254695 :C5EBF1BC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:18:16.254695 :C5EBF1BD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:18:16.254695 :C5EBF1BE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:18:16.254696 :C5EBF1BF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:18:16.254696 :C5EBF1C0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:18:16.255185 :C5EBF1C1: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-08-28 21:18:46.340355 :C5EBFC67:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:19:16.331195 :C5EC0611:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:19:16.320 2025-08-28 21:19:16.331197 :C5EC0612:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23392 pso_num=25 pso_serial#=184 2025-08-28 21:19:16.331309 :C5EC0613:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23392 osp_idx=23 osp_ver=264221 osp_pg=0 (spawn #535720) 2025-08-28 21:19:16.331310 :C5EC0614:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:19:16.331310 :C5EC0615:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=801607627 2025-08-28 21:19:16.331310 :C5EC0616:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:19:16.331311 :C5EC0617:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:19:16.331311 :C5EC0618:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:19:16.331312 :C5EC0619:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:19:16.331312 :C5EC061A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:19:16.331811 :C5EC061B: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-08-28 21:19:46.398535 :C5EC0FA3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:20:01.403369 :C5EC146F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:20:01.391 2025-08-28 21:20:01.403371 :C5EC1470:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22716 pso_num=25 pso_serial#=185 2025-08-28 21:20:01.403505 :C5EC1471:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22716 osp_idx=23 osp_ver=264222 osp_pg=0 (spawn #535722) 2025-08-28 21:20:01.403506 :C5EC1472:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:20:01.403506 :C5EC1473:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=801652705 2025-08-28 21:20:01.403507 :C5EC1474:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:20:01.403507 :C5EC1475:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:20:01.403508 :C5EC1476:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:20:01.403508 :C5EC1477:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:20:01.403509 :C5EC1478:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:20:01.403999 :C5EC1479: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-08-28 21:20:16.404984 :C5EC1935:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-28 21:20:46.491626 :C5EC22E7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:21:16.516532 :C5EC2CBA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:21:16.504 2025-08-28 21:21:16.516534 :C5EC2CBB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23512 pso_num=25 pso_serial#=186 2025-08-28 21:21:16.516649 :C5EC2CBC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23512 osp_idx=23 osp_ver=264223 osp_pg=0 (spawn #535725) 2025-08-28 21:21:16.516650 :C5EC2CBD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:21:16.516650 :C5EC2CBE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=801727815 2025-08-28 21:21:16.516651 :C5EC2CBF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:21:16.516651 :C5EC2CC0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:21:16.516651 :C5EC2CC1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:21:16.516651 :C5EC2CC2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:21:16.516652 :C5EC2CC3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:21:16.517247 :C5EC2CC4: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-08-28 21:21:46.626148 :C5EC36F2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:22:16.625180 :C5EC40AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:22:16.613 2025-08-28 21:22:16.625182 :C5EC40AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1388 pso_num=25 pso_serial#=187 2025-08-28 21:22:16.625297 :C5EC40AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1388 osp_idx=23 osp_ver=264224 osp_pg=0 (spawn #535727) 2025-08-28 21:22:16.625298 :C5EC40AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:22:16.625298 :C5EC40AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=801787924 2025-08-28 21:22:16.625298 :C5EC40B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:22:16.625299 :C5EC40B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:22:16.625299 :C5EC40B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:22:16.625300 :C5EC40B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:22:16.625301 :C5EC40B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:22:16.625760 :C5EC40B5: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-08-28 21:22:46.689061 :C5EC4A53:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:23:16.683661 :C5EC5408:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:23:16.672 2025-08-28 21:23:16.683663 :C5EC5409:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22184 pso_num=25 pso_serial#=188 2025-08-28 21:23:16.683783 :C5EC540A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22184 osp_idx=23 osp_ver=264225 osp_pg=0 (spawn #535729) 2025-08-28 21:23:16.683785 :C5EC540B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:23:16.683785 :C5EC540C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=801847987 2025-08-28 21:23:16.683786 :C5EC540D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:23:16.683786 :C5EC540E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:23:16.683786 :C5EC540F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:23:16.683787 :C5EC5410:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:23:16.683788 :C5EC5411:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:23:16.684363 :C5EC5412: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-08-28 21:23:46.740559 :C5EC5E3B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:24:16.788225 :C5EC67FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:24:16.776 2025-08-28 21:24:16.788227 :C5EC67FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22080 pso_num=25 pso_serial#=189 2025-08-28 21:24:16.788342 :C5EC67FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22080 osp_idx=23 osp_ver=264226 osp_pg=0 (spawn #535732) 2025-08-28 21:24:16.788343 :C5EC6800:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:24:16.788344 :C5EC6801:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=801908080 2025-08-28 21:24:16.788344 :C5EC6802:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:24:16.788345 :C5EC6803:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:24:16.788345 :C5EC6804:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:24:16.788345 :C5EC6805:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:24:16.788346 :C5EC6806:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:24:16.788849 :C5EC6807: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-08-28 21:24:46.844038 :C5EC71D2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:25:16.860736 :C5EC7B7C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:25:16.849 2025-08-28 21:25:16.860738 :C5EC7B7D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21508 pso_num=25 pso_serial#=190 2025-08-28 21:25:16.860855 :C5EC7B7E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21508 osp_idx=23 osp_ver=264227 osp_pg=0 (spawn #535734) 2025-08-28 21:25:16.860856 :C5EC7B7F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:25:16.860857 :C5EC7B80:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=801968158 2025-08-28 21:25:16.860857 :C5EC7B81:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:25:16.860857 :C5EC7B82:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:25:16.860858 :C5EC7B83:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:25:16.860858 :C5EC7B84:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:25:16.860859 :C5EC7B85:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:25:16.861350 :C5EC7B86: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-08-28 21:25:46.932195 :C5EC851A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:26:16.942028 :C5EC8E8B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:26:16.930 2025-08-28 21:26:16.942030 :C5EC8E8C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19768 pso_num=25 pso_serial#=191 2025-08-28 21:26:16.942145 :C5EC8E8D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19768 osp_idx=23 osp_ver=264228 osp_pg=0 (spawn #535736) 2025-08-28 21:26:16.942146 :C5EC8E8E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:26:16.942147 :C5EC8E8F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=802028237 2025-08-28 21:26:16.942147 :C5EC8E90:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:26:16.942147 :C5EC8E91:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:26:16.942147 :C5EC8E92:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:26:16.942148 :C5EC8E93:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:26:16.942148 :C5EC8E94:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:26:16.942628 :C5EC8E95: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-08-28 21:26:47.010061 :C5EC989F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:27:17.062536 :C5ECA1F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:27:17.051 2025-08-28 21:27:17.062538 :C5ECA1F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20104 pso_num=25 pso_serial#=192 2025-08-28 21:27:17.062677 :C5ECA1F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20104 osp_idx=23 osp_ver=264229 osp_pg=0 (spawn #535738) 2025-08-28 21:27:17.062677 :C5ECA1F9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:27:17.062678 :C5ECA1FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=802088362 2025-08-28 21:27:17.062678 :C5ECA1FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:27:17.062679 :C5ECA1FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:27:17.062679 :C5ECA1FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:27:17.062679 :C5ECA1FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:27:17.062680 :C5ECA1FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:27:17.063179 :C5ECA200: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-08-28 21:27:47.117536 :C5ECABE3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:28:17.141908 :C5ECB570:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:28:17.131 2025-08-28 21:28:17.141910 :C5ECB571:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10604 pso_num=25 pso_serial#=193 2025-08-28 21:28:17.142045 :C5ECB572:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10604 osp_idx=23 osp_ver=264230 osp_pg=0 (spawn #535740) 2025-08-28 21:28:17.142046 :C5ECB573:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:28:17.142046 :C5ECB574:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=159 time=802148440 2025-08-28 21:28:17.142046 :C5ECB575:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:28:17.142047 :C5ECB576:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:28:17.142047 :C5ECB577:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:28:17.142047 :C5ECB578:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:28:17.142048 :C5ECB579:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:28:17.142615 :C5ECB57A: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-08-28 21:28:47.221436 :C5ECC07E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:29:17.212344 :C5ECCA02:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:29:17.200 2025-08-28 21:29:17.212346 :C5ECCA03:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21052 pso_num=25 pso_serial#=194 2025-08-28 21:29:17.212461 :C5ECCA04:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21052 osp_idx=23 osp_ver=264231 osp_pg=0 (spawn #535743) 2025-08-28 21:29:17.212462 :C5ECCA05:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:29:17.212462 :C5ECCA06:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=160 time=802208502 2025-08-28 21:29:17.212463 :C5ECCA07:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:29:17.212463 :C5ECCA08:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:29:17.212463 :C5ECCA09:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:29:17.212464 :C5ECCA0A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:29:17.212464 :C5ECCA0B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:29:17.212966 :C5ECCA0C: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-08-28 21:29:47.310924 :C5ECD3DA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:30:02.281059 :C5ECD8E1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:30:02.270 2025-08-28 21:30:02.281061 :C5ECD8E2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20616 pso_num=25 pso_serial#=195 2025-08-28 21:30:02.281193 :C5ECD8E3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20616 osp_idx=23 osp_ver=264232 osp_pg=0 (spawn #535745) 2025-08-28 21:30:02.281193 :C5ECD8E4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:30:02.281194 :C5ECD8E5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=161 time=802253580 2025-08-28 21:30:02.281194 :C5ECD8E6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:30:02.281194 :C5ECD8E7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:30:02.281195 :C5ECD8E8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:30:02.281195 :C5ECD8E9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:30:02.281195 :C5ECD8EA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:30:02.281684 :C5ECD8EB: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-08-28 21:30:16.612062 :C5ECDDB4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-28 21:30:17.284096 :C5ECDDF2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-28 21:30:47.347668 :C5ECE798:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:31:17.631226 :C5ECF1D1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:31:17.626 2025-08-28 21:31:17.631229 :C5ECF1D2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21796 pso_num=25 pso_serial#=196 2025-08-28 21:31:17.631371 :C5ECF1D3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21796 osp_idx=23 osp_ver=264233 osp_pg=0 (spawn #535748) 2025-08-28 21:31:17.631372 :C5ECF1D4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:31:17.631373 :C5ECF1D5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=162 time=802328940 2025-08-28 21:31:17.631373 :C5ECF1D6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:31:17.631373 :C5ECF1D7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:31:17.631374 :C5ECF1D8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:31:17.631374 :C5ECF1D9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:31:17.631375 :C5ECF1DA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:31:17.631892 :C5ECF1DB: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-08-28 21:31:17.631941 :C5ECF1E2: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-08-28 21:41:18.516445 :C5EDB68A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:41:18.974479 :C5EDB6DA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:41:18.970 2025-08-28 21:41:18.974481 :C5EDB6DB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23220 pso_num=25 pso_serial#=197 2025-08-28 21:41:18.974626 :C5EDB6DC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23220 osp_idx=23 osp_ver=264234 osp_pg=0 (spawn #535771) 2025-08-28 21:41:18.974626 :C5EDB6DD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:41:18.974627 :C5EDB6DE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=163 time=802930283 2025-08-28 21:41:18.974627 :C5EDB6DF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:41:18.974627 :C5EDB6E0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:41:18.974628 :C5EDB6E1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:41:18.974628 :C5EDB6E2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:41:18.974629 :C5EDB6E3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:41:18.975182 :C5EDB6E4: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-08-28 21:41:18.975232 :C5EDB6EB: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-08-28 21:51:19.804357 :C5EE7C7E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-28 21:51:20.152817 :C5EE7CCA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-28 21:51:20.148 2025-08-28 21:51:20.152820 :C5EE7CCB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23248 pso_num=25 pso_serial#=198 2025-08-28 21:51:20.152956 :C5EE7CCC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23248 osp_idx=23 osp_ver=264235 osp_pg=0 (spawn #535793) 2025-08-28 21:51:20.152957 :C5EE7CCD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:51:20.152957 :C5EE7CCE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=803531455 2025-08-28 21:51:20.152958 :C5EE7CCF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:51:20.152958 :C5EE7CD0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:51:20.152958 :C5EE7CD1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:51:20.152959 :C5EE7CD2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:51:20.152959 :C5EE7CD3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:51:20.153461 :C5EE7CD4: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-08-28 21:51:20.153508 :C5EE7CDC: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: 23248, W000)