Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250531060006\orcl_w000_15108_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:37249M/63366M, Ph+PgF:44127M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15108, image: ORACLE.EXE (W000) *** 2025-05-31 06:00:06.212 *** SESSION ID:(177.13237) 2025-05-31 06:00:06.212 *** 2025-05-31 06:00:06.212 Process diagnostic dump for ORACLE.EXE (W000), OS id=15108, pid: 22, proc_ser: 47, sid: 177, sess_ser: 13237 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=112 seq_num=113 snap_id=1 wait times: snap=4.740126 sec, exc=4.740126 sec, total=4.740126 sec wait times: max=5.000000 sec, heur=9 min 20 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.000028 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=111 seq_num=112 snap_id=1 wait times: snap=5.015569 sec, exc=5.015569 sec, total=5.015569 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=110 seq_num=111 snap_id=1 wait times: snap=5.009176 sec, exc=5.009176 sec, total=5.009176 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=109 seq_num=110 snap_id=1 wait times: snap=5.010152 sec, exc=5.010152 sec, total=5.010152 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=108 seq_num=109 snap_id=1 wait times: snap=5.014738 sec, exc=5.014738 sec, total=5.014738 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=107 seq_num=108 snap_id=1 wait times: snap=5.015260 sec, exc=5.015260 sec, total=5.015260 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=5.000768 sec, exc=5.000768 sec, total=5.000768 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=105 seq_num=106 snap_id=1 wait times: snap=5.014762 sec, exc=5.014762 sec, total=5.014762 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 7: 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.011005 sec, exc=5.011005 sec, total=5.011005 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 8: 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.010157 sec, exc=5.010157 sec, total=5.010157 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: 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.009228 sec, exc=5.009228 sec, total=5.009228 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 177 serial 13237 --------------------------------------------------- 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, 05:58:06 - 06:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-31 06:00:06.212 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15108, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-31 03:20:25.979851 :9E44DCBD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 03:20:25.979851 :9E44DCBE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 03:20:25.980345 :9E44DCBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 03:20:25.980392 :9E44DCC7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 03:30:26.931873 :9E45A1F0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 03:30:27.263022 :9E45A239:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 03:30:27.258 2025-05-31 03:30:27.263024 :9E45A23A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8784 pso_num=22 pso_serial#=34 2025-05-31 03:30:27.263159 :9E45A23B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8784 osp_idx=0 osp_ver=252556 osp_pg=0 (spawn #236574) 2025-05-31 03:30:27.263160 :9E45A23C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 03:30:27.263161 :9E45A23D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=1637985907 2025-05-31 03:30:27.263161 :9E45A23E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 03:30:27.263161 :9E45A23F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 03:30:27.263162 :9E45A240:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 03:30:27.263162 :9E45A241:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 03:30:27.263162 :9E45A242:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 03:30:27.263689 :9E45A243:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 03:30:27.263740 :9E45A24D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 03:40:28.145804 :9E4664A2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 03:40:28.488751 :9E4664F6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 03:40:28.484 2025-05-31 03:40:28.488754 :9E4664F7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7776 pso_num=22 pso_serial#=35 2025-05-31 03:40:28.488886 :9E4664F8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7776 osp_idx=0 osp_ver=252557 osp_pg=0 (spawn #236597) 2025-05-31 03:40:28.488887 :9E4664F9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 03:40:28.488887 :9E4664FA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=1638587141 2025-05-31 03:40:28.488887 :9E4664FB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 03:40:28.488887 :9E4664FC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 03:40:28.488888 :9E4664FD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 03:40:28.488888 :9E4664FE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 03:40:28.488888 :9E4664FF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 03:40:28.489378 :9E466500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 03:40:28.489432 :9E46650A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 03:50:29.341569 :9E47279B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 03:50:29.658677 :9E4727E6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 03:50:29.654 2025-05-31 03:50:29.658679 :9E4727E7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11620 pso_num=22 pso_serial#=36 2025-05-31 03:50:29.658820 :9E4727E8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11620 osp_idx=0 osp_ver=252558 osp_pg=0 (spawn #236620) 2025-05-31 03:50:29.658820 :9E4727E9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 03:50:29.658821 :9E4727EA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=1639188313 2025-05-31 03:50:29.658821 :9E4727EB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 03:50:29.658821 :9E4727EC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 03:50:29.658822 :9E4727ED:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 03:50:29.658822 :9E4727EE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 03:50:29.658822 :9E4727EF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 03:50:29.659304 :9E4727F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 03:50:29.659352 :9E4727F8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:00:30.622703 :9E47EB63:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 04:00:30.955583 :9E47EBAB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 04:00:30.950 2025-05-31 04:00:30.955585 :9E47EBAC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11564 pso_num=22 pso_serial#=37 2025-05-31 04:00:30.955721 :9E47EBAD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11564 osp_idx=0 osp_ver=252559 osp_pg=0 (spawn #236642) 2025-05-31 04:00:30.955721 :9E47EBAE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 04:00:30.955722 :9E47EBAF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=1639789610 2025-05-31 04:00:30.955723 :9E47EBB0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 04:00:30.955723 :9E47EBB1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 04:00:30.955723 :9E47EBB2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 04:00:30.955724 :9E47EBB3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 04:00:30.955725 :9E47EBB4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 04:00:30.956251 :9E47EBB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 04:00:30.956304 :9E47EBBF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:10:31.916065 :9E48B93F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 04:10:32.295773 :9E48B991:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 04:10:32.291 2025-05-31 04:10:32.295776 :9E48B992:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15320 pso_num=22 pso_serial#=38 2025-05-31 04:10:32.295970 :9E48B993:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15320 osp_idx=0 osp_ver=252560 osp_pg=0 (spawn #236668) 2025-05-31 04:10:32.295970 :9E48B994:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 04:10:32.295971 :9E48B995:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=1640390938 2025-05-31 04:10:32.295971 :9E48B996:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 04:10:32.295972 :9E48B997:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 04:10:32.295972 :9E48B998:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 04:10:32.295972 :9E48B999:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 04:10:32.295973 :9E48B99A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 04:10:32.296464 :9E48B99B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 04:10:32.296508 :9E48B9A2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:20:33.263569 :9E4981B0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 04:20:33.565173 :9E4981F7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 04:20:33.560 2025-05-31 04:20:33.565175 :9E4981F8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14120 pso_num=22 pso_serial#=39 2025-05-31 04:20:33.565315 :9E4981F9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14120 osp_idx=0 osp_ver=252561 osp_pg=0 (spawn #236691) 2025-05-31 04:20:33.565316 :9E4981FA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 04:20:33.565316 :9E4981FB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=1640992219 2025-05-31 04:20:33.565316 :9E4981FC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 04:20:33.565317 :9E4981FD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 04:20:33.565317 :9E4981FE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 04:20:33.565318 :9E4981FF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 04:20:33.565318 :9E498200:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 04:20:33.565829 :9E498201:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 04:20:33.565879 :9E498209:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:30:34.487079 :9E4A4482:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 04:30:34.976134 :9E4A44DB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 04:30:34.971 2025-05-31 04:30:34.976136 :9E4A44DC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13992 pso_num=22 pso_serial#=40 2025-05-31 04:30:34.976269 :9E4A44DD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13992 osp_idx=0 osp_ver=252562 osp_pg=0 (spawn #236713) 2025-05-31 04:30:34.976270 :9E4A44DE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 04:30:34.976271 :9E4A44DF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=1641593625 2025-05-31 04:30:34.976271 :9E4A44E0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 04:30:34.976271 :9E4A44E1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 04:30:34.976272 :9E4A44E2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 04:30:34.976272 :9E4A44E3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 04:30:34.976272 :9E4A44E4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 04:30:34.976790 :9E4A44E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 04:30:34.976832 :9E4A44ED:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:40:35.887498 :9E4B084D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 04:40:36.251593 :9E4B0894:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 04:40:36.247 2025-05-31 04:40:36.251596 :9E4B0895:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14260 pso_num=22 pso_serial#=41 2025-05-31 04:40:36.251729 :9E4B0896:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14260 osp_idx=0 osp_ver=252563 osp_pg=0 (spawn #236736) 2025-05-31 04:40:36.251730 :9E4B0897:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 04:40:36.251730 :9E4B0898:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=1642194891 2025-05-31 04:40:36.251731 :9E4B0899:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 04:40:36.251731 :9E4B089A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 04:40:36.251732 :9E4B089B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 04:40:36.251732 :9E4B089C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 04:40:36.251732 :9E4B089D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 04:40:36.252216 :9E4B089E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 04:40:36.252266 :9E4B08A4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:50:37.244638 :9E4BCB14:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 04:50:37.546304 :9E4BCB5B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 04:50:37.541 2025-05-31 04:50:37.546305 :9E4BCB5C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12436 pso_num=22 pso_serial#=42 2025-05-31 04:50:37.546436 :9E4BCB5D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12436 osp_idx=0 osp_ver=252564 osp_pg=0 (spawn #236759) 2025-05-31 04:50:37.546437 :9E4BCB5E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 04:50:37.546437 :9E4BCB5F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=1642796188 2025-05-31 04:50:37.546438 :9E4BCB60:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 04:50:37.546438 :9E4BCB61:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 04:50:37.546439 :9E4BCB62:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 04:50:37.546439 :9E4BCB63:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 04:50:37.546439 :9E4BCB64:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 04:50:37.546981 :9E4BCB65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 04:50:37.547032 :9E4BCB6F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 04:55:59.452504 :9E4C336D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-31 05:00:39.008864 :9E4C8E2B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-31 05:10:39.974758 :9E4D5968:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 05:10:40.244977 :9E4D59A6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 05:10:40.241 2025-05-31 05:10:40.244979 :9E4D59A7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9804 pso_num=22 pso_serial#=43 2025-05-31 05:10:40.245118 :9E4D59A8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9804 osp_idx=0 osp_ver=252565 osp_pg=0 (spawn #236806) 2025-05-31 05:10:40.245119 :9E4D59A9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 05:10:40.245119 :9E4D59AA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=1643998891 2025-05-31 05:10:40.245119 :9E4D59AB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 05:10:40.245120 :9E4D59AC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 05:10:40.245120 :9E4D59AD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 05:10:40.245120 :9E4D59AE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 05:10:40.245121 :9E4D59AF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 05:10:40.245623 :9E4D59B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 05:10:40.245666 :9E4D59B8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 05:20:41.146827 :9E4E1B6D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 05:20:41.542180 :9E4E1BAC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 05:20:41.537 2025-05-31 05:20:41.542182 :9E4E1BAD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14696 pso_num=22 pso_serial#=44 2025-05-31 05:20:41.542321 :9E4E1BAE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14696 osp_idx=0 osp_ver=252566 osp_pg=0 (spawn #236829) 2025-05-31 05:20:41.542322 :9E4E1BAF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 05:20:41.542322 :9E4E1BB0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=1644600188 2025-05-31 05:20:41.542322 :9E4E1BB1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 05:20:41.542323 :9E4E1BB2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 05:20:41.542323 :9E4E1BB3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 05:20:41.542323 :9E4E1BB4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 05:20:41.542324 :9E4E1BB5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 05:20:41.542828 :9E4E1BB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 05:20:41.542874 :9E4E1BBE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 05:30:42.441447 :9E4EDE89:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 05:30:42.868103 :9E4EDF01:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 05:30:42.863 2025-05-31 05:30:42.868105 :9E4EDF02:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15012 pso_num=22 pso_serial#=45 2025-05-31 05:30:42.868238 :9E4EDF03:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15012 osp_idx=0 osp_ver=252567 osp_pg=0 (spawn #236851) 2025-05-31 05:30:42.868239 :9E4EDF04:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 05:30:42.868239 :9E4EDF05:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=1645201516 2025-05-31 05:30:42.868240 :9E4EDF06:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 05:30:42.868240 :9E4EDF07:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 05:30:42.868241 :9E4EDF08:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 05:30:42.868241 :9E4EDF09:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 05:30:42.868241 :9E4EDF0A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 05:30:42.868744 :9E4EDF0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 05:30:42.868793 :9E4EDF14:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 05:40:43.820533 :9E4FA3CB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 05:40:44.247103 :9E4FA434:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 05:40:44.242 2025-05-31 05:40:44.247105 :9E4FA435:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14828 pso_num=22 pso_serial#=46 2025-05-31 05:40:44.247243 :9E4FA436:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14828 osp_idx=0 osp_ver=252568 osp_pg=0 (spawn #236874) 2025-05-31 05:40:44.247243 :9E4FA437:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 05:40:44.247244 :9E4FA438:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=1645802891 2025-05-31 05:40:44.247244 :9E4FA439:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 05:40:44.247244 :9E4FA43A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 05:40:44.247245 :9E4FA43B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 05:40:44.247245 :9E4FA43C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 05:40:44.247245 :9E4FA43D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 05:40:44.247734 :9E4FA43E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 05:40:44.247784 :9E4FA445:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-31 05:50:45.267182 :9E506756:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 05:50:45.600060 :9E5067D1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 05:50:45.595 2025-05-31 05:50:45.600062 :9E5067D2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15108 pso_num=22 pso_serial#=47 2025-05-31 05:50:45.600197 :9E5067D3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15108 osp_idx=0 osp_ver=252569 osp_pg=0 (spawn #236897) 2025-05-31 05:50:45.600198 :9E5067D4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 05:50:45.600198 :9E5067D5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=1646404250 2025-05-31 05:50:45.600198 :9E5067D6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 05:50:45.600199 :9E5067D7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 05:50:45.600199 :9E5067D8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 05:50:45.600199 :9E5067D9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 05:50:45.600200 :9E5067DA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 05:50:45.600692 :9E5067DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 05:50:45.600736 :9E5067E3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15108, W000)