Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250803180911\orcl_w000_18460_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:35775M/63366M, Ph+PgF:41408M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 18460, image: ORACLE.EXE (W000) *** 2025-08-03 18:09:11.966 *** SESSION ID:(177.20069) 2025-08-03 18:09:11.966 *** 2025-08-03 18:09:11.966 Process diagnostic dump for ORACLE.EXE (W000), OS id=18460, pid: 22, proc_ser: 77, sid: 177, sess_ser: 20069 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=181 seq_num=182 snap_id=1 wait times: snap=0.571427 sec, exc=0.571427 sec, total=0.571427 sec wait times: max=5.000000 sec, heur=5 min 35 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.000030 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=180 seq_num=181 snap_id=1 wait times: snap=5.004050 sec, exc=5.004050 sec, total=5.004050 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=179 seq_num=180 snap_id=1 wait times: snap=5.014426 sec, exc=5.014426 sec, total=5.014426 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=178 seq_num=179 snap_id=1 wait times: snap=5.014720 sec, exc=5.014720 sec, total=5.014720 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=177 seq_num=178 snap_id=1 wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 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=176 seq_num=177 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=175 seq_num=176 snap_id=1 wait times: snap=5.008299 sec, exc=5.008299 sec, total=5.008299 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=174 seq_num=175 snap_id=1 wait times: snap=5.000325 sec, exc=5.000325 sec, total=5.000325 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=173 seq_num=174 snap_id=1 wait times: snap=5.005748 sec, exc=5.005748 sec, total=5.005748 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=172 seq_num=173 snap_id=1 wait times: snap=5.004617 sec, exc=5.004617 sec, total=5.004617 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=171 seq_num=172 snap_id=1 wait times: snap=5.004650 sec, exc=5.004650 sec, total=5.004650 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 20069 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 18:07:11 - 18:09:11] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-03 18:09:11.966 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 18460, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-03 15:24:01.568494 :BAD38ADA: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-08-03 15:34:02.308818 :BAD45490:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 15:34:02.797999 :BAD454F3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 15:34:02.793 2025-08-03 15:34:02.798001 :BAD454F4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20416 pso_num=22 pso_serial#=64 2025-08-03 15:34:02.798140 :BAD454F5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20416 osp_idx=0 osp_ver=477241 osp_pg=0 (spawn #451597) 2025-08-03 15:34:02.798140 :BAD454F6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 15:34:02.798141 :BAD454F7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=172 time=2915912111 2025-08-03 15:34:02.798142 :BAD454F8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 15:34:02.798142 :BAD454F9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 15:34:02.798142 :BAD454FA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 15:34:02.798143 :BAD454FB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 15:34:02.798143 :BAD454FC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 15:34:02.798639 :BAD454FD: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-08-03 15:34:02.798683 :BAD45504: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-08-03 15:44:03.669238 :BAD51F05:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 15:44:04.095755 :BAD51F70:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 15:44:04.090 2025-08-03 15:44:04.095757 :BAD51F71:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19388 pso_num=22 pso_serial#=65 2025-08-03 15:44:04.095885 :BAD51F72:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19388 osp_idx=0 osp_ver=477242 osp_pg=0 (spawn #451619) 2025-08-03 15:44:04.095886 :BAD51F73:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 15:44:04.095886 :BAD51F74:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=173 time=2916513408 2025-08-03 15:44:04.095886 :BAD51F75:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 15:44:04.095887 :BAD51F76:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 15:44:04.095887 :BAD51F77:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 15:44:04.095887 :BAD51F78:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 15:44:04.095888 :BAD51F79:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 15:44:04.096377 :BAD51F7A: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-08-03 15:44:04.096421 :BAD51F81: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-08-03 15:54:04.952484 :BAD5E901:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 15:54:05.426030 :BAD5E935:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 15:54:05.421 2025-08-03 15:54:05.426032 :BAD5E936:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13804 pso_num=22 pso_serial#=66 2025-08-03 15:54:05.426171 :BAD5E937:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13804 osp_idx=0 osp_ver=477243 osp_pg=0 (spawn #451642) 2025-08-03 15:54:05.426172 :BAD5E938:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 15:54:05.426172 :BAD5E939:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=174 time=2917114736 2025-08-03 15:54:05.426172 :BAD5E93A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 15:54:05.426173 :BAD5E93B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 15:54:05.426173 :BAD5E93C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 15:54:05.426173 :BAD5E93D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 15:54:05.426174 :BAD5E93E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 15:54:05.426694 :BAD5E93F: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-08-03 15:54:05.426752 :BAD5E946: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-08-03 15:58:39.973182 :BAD6454F: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-08-03 16:04:06.554606 :BAD6B5B0: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-08-03 16:14:07.379773 :BAD7867B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 16:14:07.790897 :BAD786B4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 16:14:07.786 2025-08-03 16:14:07.790899 :BAD786B5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18352 pso_num=22 pso_serial#=67 2025-08-03 16:14:07.791040 :BAD786B6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18352 osp_idx=0 osp_ver=477244 osp_pg=0 (spawn #451690) 2025-08-03 16:14:07.791040 :BAD786B7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 16:14:07.791041 :BAD786B8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=175 time=2918317095 2025-08-03 16:14:07.791041 :BAD786B9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 16:14:07.791041 :BAD786BA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 16:14:07.791042 :BAD786BB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 16:14:07.791042 :BAD786BC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 16:14:07.791042 :BAD786BD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 16:14:07.791551 :BAD786BE: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-08-03 16:14:07.791606 :BAD786C4: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-08-03 16:24:08.700452 :BAD853B2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 16:24:09.017731 :BAD853EB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 16:24:09.012 2025-08-03 16:24:09.017734 :BAD853EC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19892 pso_num=22 pso_serial#=68 2025-08-03 16:24:09.017898 :BAD853ED:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19892 osp_idx=0 osp_ver=477245 osp_pg=0 (spawn #451713) 2025-08-03 16:24:09.017899 :BAD853EE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 16:24:09.017899 :BAD853EF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=176 time=2918918329 2025-08-03 16:24:09.017900 :BAD853F0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 16:24:09.017900 :BAD853F1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 16:24:09.017901 :BAD853F2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 16:24:09.017901 :BAD853F3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 16:24:09.017901 :BAD853F4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 16:24:09.018406 :BAD853F5: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-08-03 16:24:09.018453 :BAD853FD: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-08-03 16:34:09.895620 :BAD91E2B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 16:34:10.259919 :BAD91E72:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 16:34:10.255 2025-08-03 16:34:10.259921 :BAD91E73:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12540 pso_num=22 pso_serial#=69 2025-08-03 16:34:10.260059 :BAD91E74:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12540 osp_idx=0 osp_ver=477246 osp_pg=0 (spawn #451736) 2025-08-03 16:34:10.260060 :BAD91E75:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 16:34:10.260060 :BAD91E76:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=177 time=2919519564 2025-08-03 16:34:10.260060 :BAD91E77:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 16:34:10.260061 :BAD91E78:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 16:34:10.260061 :BAD91E79:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 16:34:10.260061 :BAD91E7A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 16:34:10.260062 :BAD91E7B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 16:34:10.260558 :BAD91E7C: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-08-03 16:34:10.260605 :BAD91E83: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-08-03 16:44:11.176753 :BAD9E8F7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 16:44:11.494012 :BAD9E92D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 16:44:11.488 2025-08-03 16:44:11.494014 :BAD9E92E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19284 pso_num=22 pso_serial#=70 2025-08-03 16:44:11.494152 :BAD9E92F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19284 osp_idx=0 osp_ver=477247 osp_pg=0 (spawn #451758) 2025-08-03 16:44:11.494153 :BAD9E930:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 16:44:11.494154 :BAD9E931:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=178 time=2920120798 2025-08-03 16:44:11.494154 :BAD9E932:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 16:44:11.494154 :BAD9E933:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 16:44:11.494155 :BAD9E934:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 16:44:11.494155 :BAD9E935:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 16:44:11.494156 :BAD9E936:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 16:44:11.494637 :BAD9E937: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-08-03 16:44:11.494681 :BAD9E93E: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-08-03 16:54:12.331588 :BADAB35D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 16:54:12.711828 :BADAB395:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 16:54:12.707 2025-08-03 16:54:12.711830 :BADAB396:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14372 pso_num=22 pso_serial#=71 2025-08-03 16:54:12.711976 :BADAB397:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14372 osp_idx=0 osp_ver=477248 osp_pg=0 (spawn #451781) 2025-08-03 16:54:12.711976 :BADAB398:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 16:54:12.711977 :BADAB399:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=179 time=2920722017 2025-08-03 16:54:12.711977 :BADAB39A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 16:54:12.711978 :BADAB39B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 16:54:12.711978 :BADAB39C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 16:54:12.711978 :BADAB39D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 16:54:12.711979 :BADAB39E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 16:54:12.712513 :BADAB39F: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-08-03 16:54:12.712562 :BADAB3A6: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-08-03 17:04:13.556566 :BADB802F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 17:04:13.894307 :BADB806C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 17:04:13.889 2025-08-03 17:04:13.894309 :BADB806D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=636 pso_num=22 pso_serial#=72 2025-08-03 17:04:13.894450 :BADB806E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=636 osp_idx=0 osp_ver=477249 osp_pg=0 (spawn #451806) 2025-08-03 17:04:13.894451 :BADB806F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 17:04:13.894452 :BADB8070:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=180 time=2921323204 2025-08-03 17:04:13.894452 :BADB8071:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 17:04:13.894452 :BADB8072:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 17:04:13.894453 :BADB8073:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 17:04:13.894453 :BADB8074:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 17:04:13.894453 :BADB8075:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 17:04:13.894952 :BADB8076: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-08-03 17:04:13.895002 :BADB807E: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-08-03 17:14:14.714048 :BADC53A5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 17:14:15.000258 :BADC53DB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 17:14:14.995 2025-08-03 17:14:15.000260 :BADC53DC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16664 pso_num=22 pso_serial#=73 2025-08-03 17:14:15.000405 :BADC53DD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16664 osp_idx=0 osp_ver=477250 osp_pg=0 (spawn #451829) 2025-08-03 17:14:15.000406 :BADC53DE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 17:14:15.000406 :BADC53DF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=181 time=2921924314 2025-08-03 17:14:15.000407 :BADC53E0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 17:14:15.000407 :BADC53E1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 17:14:15.000407 :BADC53E2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 17:14:15.000408 :BADC53E3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 17:14:15.000408 :BADC53E4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 17:14:15.000936 :BADC53E5: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-08-03 17:14:15.000983 :BADC53EC: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-08-03 17:24:15.943507 :BADD1DC5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 17:24:16.213774 :BADD1DFC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 17:24:16.209 2025-08-03 17:24:16.213776 :BADD1DFD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10356 pso_num=22 pso_serial#=74 2025-08-03 17:24:16.213913 :BADD1DFE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10356 osp_idx=0 osp_ver=477251 osp_pg=0 (spawn #451852) 2025-08-03 17:24:16.213913 :BADD1DFF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 17:24:16.213914 :BADD1E00:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=182 time=2922525517 2025-08-03 17:24:16.213914 :BADD1E01:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 17:24:16.213915 :BADD1E02:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 17:24:16.213915 :BADD1E03:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 17:24:16.213915 :BADD1E04:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 17:24:16.213916 :BADD1E05:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 17:24:16.214457 :BADD1E06: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-08-03 17:24:16.214505 :BADD1E0D: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-08-03 17:34:17.099479 :BADDE83C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 17:34:17.448059 :BADDE874:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 17:34:17.443 2025-08-03 17:34:17.448062 :BADDE875:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19272 pso_num=22 pso_serial#=75 2025-08-03 17:34:17.448267 :BADDE876:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19272 osp_idx=0 osp_ver=477252 osp_pg=0 (spawn #451875) 2025-08-03 17:34:17.448268 :BADDE877:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 17:34:17.448269 :BADDE878:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=183 time=2923126751 2025-08-03 17:34:17.448269 :BADDE879:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 17:34:17.448269 :BADDE87A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 17:34:17.448270 :BADDE87B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 17:34:17.448270 :BADDE87C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 17:34:17.448270 :BADDE87D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 17:34:17.448797 :BADDE87E: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-08-03 17:34:17.448848 :BADDE886: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-08-03 17:44:18.251370 :BADEB237:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 17:44:18.631035 :BADEB27B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 17:44:18.626 2025-08-03 17:44:18.631038 :BADEB27C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19584 pso_num=22 pso_serial#=76 2025-08-03 17:44:18.631198 :BADEB27D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19584 osp_idx=0 osp_ver=477253 osp_pg=0 (spawn #451897) 2025-08-03 17:44:18.631198 :BADEB27E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 17:44:18.631199 :BADEB27F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=184 time=2923727939 2025-08-03 17:44:18.631199 :BADEB280:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 17:44:18.631200 :BADEB281:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 17:44:18.631200 :BADEB282:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 17:44:18.631200 :BADEB283:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 17:44:18.631200 :BADEB284:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 17:44:18.631703 :BADEB285: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-08-03 17:44:18.631754 :BADEB28C: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-08-03 17:54:19.501363 :BADF7CCF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 17:54:19.834423 :BADF7D05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 17:54:19.830 2025-08-03 17:54:19.834426 :BADF7D06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18460 pso_num=22 pso_serial#=77 2025-08-03 17:54:19.834571 :BADF7D07:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18460 osp_idx=0 osp_ver=477254 osp_pg=0 (spawn #451920) 2025-08-03 17:54:19.834572 :BADF7D08:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 17:54:19.834573 :BADF7D09:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=185 time=2924329142 2025-08-03 17:54:19.834573 :BADF7D0A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 17:54:19.834574 :BADF7D0B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 17:54:19.834574 :BADF7D0C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 17:54:19.834575 :BADF7D0D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 17:54:19.834575 :BADF7D0E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 17:54:19.835084 :BADF7D0F: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-08-03 17:54:19.835133 :BADF7D19: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-08-03 18:03:36.373110 :BAE039ED: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-08-03 18:04:21.031865 :BAE049A8: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 18460, W000)