Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823220207\orcl_w000_21768_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:35281M/63366M, Ph+PgF:40380M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 21768, image: ORACLE.EXE (W000) *** 2025-08-23 22:02:07.579 *** SESSION ID:(193.28219) 2025-08-23 22:02:07.579 *** 2025-08-23 22:02:07.579 Process diagnostic dump for ORACLE.EXE (W000), OS id=21768, pid: 24, proc_ser: 204, sid: 193, sess_ser: 28219 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=0.158786 sec, exc=0.158786 sec, total=0.158786 sec wait times: max=5.000000 sec, heur=6 min 25 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.000032 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.004047 sec, exc=5.004047 sec, total=5.004047 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.001733 sec, exc=5.001733 sec, total=5.001733 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.001247 sec, exc=5.001247 sec, total=5.001247 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.000206 sec, exc=5.000206 sec, total=5.000206 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.010573 sec, exc=5.010573 sec, total=5.010573 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.014330 sec, exc=5.014330 sec, total=5.014330 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=70 seq_num=71 snap_id=1 wait times: snap=5.010147 sec, exc=5.010147 sec, total=5.010147 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.014731 sec, exc=5.014731 sec, total=5.014731 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=68 seq_num=69 snap_id=1 wait times: snap=5.004621 sec, exc=5.004621 sec, total=5.004621 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 193 serial 28219 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 22:00:07 - 22:02:07] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 22:02:07.579 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 21768, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 19:25:22.423653 :C3B1F99D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 19:25:22.423654 :C3B1F99E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 19:25:22.424206 :C3B1F99F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 19:25:22.424259 :C3B1F9A6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 19:35:23.303383 :C3B2BD33:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 19:35:23.761846 :C3B2BD97:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 19:35:23.756 2025-08-23 19:35:23.761848 :C3B2BD98:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22200 pso_num=24 pso_serial#=191 2025-08-23 19:35:23.761989 :C3B2BD99:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22200 osp_idx=22 osp_ver=776931 osp_pg=0 (spawn #518820) 2025-08-23 19:35:23.761990 :C3B2BD9A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 19:35:23.761990 :C3B2BD9B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=44 time=363425768 2025-08-23 19:35:23.761990 :C3B2BD9C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 19:35:23.761991 :C3B2BD9D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 19:35:23.761991 :C3B2BD9E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 19:35:23.761992 :C3B2BD9F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 19:35:23.761992 :C3B2BDA0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 19:35:23.762523 :C3B2BDA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 19:35:23.762570 :C3B2BDA8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 19:45:24.771500 :C3B381EB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 19:45:25.260567 :C3B38247:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 19:45:25.256 2025-08-23 19:45:25.260569 :C3B38248:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12980 pso_num=24 pso_serial#=192 2025-08-23 19:45:25.260752 :C3B38249:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12980 osp_idx=22 osp_ver=776932 osp_pg=0 (spawn #518843) 2025-08-23 19:45:25.260753 :C3B3824A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 19:45:25.260754 :C3B3824B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=45 time=364027268 2025-08-23 19:45:25.260754 :C3B3824C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 19:45:25.260754 :C3B3824D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 19:45:25.260755 :C3B3824E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 19:45:25.260755 :C3B3824F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 19:45:25.260756 :C3B38250:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 19:45:25.261261 :C3B38251:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 19:45:25.261305 :C3B38259:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 19:55:26.127387 :C3B44706:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 19:55:26.523522 :C3B4477C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 19:55:26.518 2025-08-23 19:55:26.523526 :C3B4477D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17496 pso_num=24 pso_serial#=193 2025-08-23 19:55:26.523655 :C3B4477E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17496 osp_idx=22 osp_ver=776933 osp_pg=0 (spawn #518866) 2025-08-23 19:55:26.523656 :C3B4477F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 19:55:26.523656 :C3B44780:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=46 time=364628533 2025-08-23 19:55:26.523657 :C3B44781:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 19:55:26.523657 :C3B44782:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 19:55:26.523657 :C3B44783:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 19:55:26.523658 :C3B44784:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 19:55:26.523658 :C3B44785:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 19:55:26.524166 :C3B44786:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 19:55:26.524213 :C3B44790:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:05:27.426631 :C3B50E5C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 20:05:27.838387 :C3B50EBB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 20:05:27.833 2025-08-23 20:05:27.838390 :C3B50EBC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19660 pso_num=24 pso_serial#=194 2025-08-23 20:05:27.838527 :C3B50EBD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19660 osp_idx=22 osp_ver=776934 osp_pg=0 (spawn #518891) 2025-08-23 20:05:27.838527 :C3B50EBE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 20:05:27.838528 :C3B50EBF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=47 time=365229846 2025-08-23 20:05:27.838528 :C3B50EC0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 20:05:27.838528 :C3B50EC1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 20:05:27.838529 :C3B50EC2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 20:05:27.838529 :C3B50EC3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 20:05:27.838529 :C3B50EC4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 20:05:27.839003 :C3B50EC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 20:05:27.839047 :C3B50ECC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:15:28.638478 :C3B5D9BD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 20:15:29.064935 :C3B5DA28:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 20:15:29.060 2025-08-23 20:15:29.064937 :C3B5DA29:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16248 pso_num=24 pso_serial#=195 2025-08-23 20:15:29.065071 :C3B5DA2A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16248 osp_idx=22 osp_ver=776935 osp_pg=0 (spawn #518914) 2025-08-23 20:15:29.065071 :C3B5DA2B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 20:15:29.065072 :C3B5DA2C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=48 time=365831080 2025-08-23 20:15:29.065072 :C3B5DA2D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 20:15:29.065073 :C3B5DA2E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 20:15:29.065073 :C3B5DA2F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 20:15:29.065073 :C3B5DA30:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 20:15:29.065074 :C3B5DA31:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 20:15:29.065555 :C3B5DA32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 20:15:29.065602 :C3B5DA39:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:25:29.925601 :C3B6A12D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 20:25:30.289749 :C3B6A189:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 20:25:30.285 2025-08-23 20:25:30.289751 :C3B6A18A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14024 pso_num=24 pso_serial#=196 2025-08-23 20:25:30.289889 :C3B6A18B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14024 osp_idx=22 osp_ver=776936 osp_pg=0 (spawn #518937) 2025-08-23 20:25:30.289889 :C3B6A18C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 20:25:30.289890 :C3B6A18D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=49 time=366432299 2025-08-23 20:25:30.289890 :C3B6A18E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 20:25:30.289890 :C3B6A18F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 20:25:30.289891 :C3B6A190:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 20:25:30.289891 :C3B6A191:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 20:25:30.289891 :C3B6A192:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 20:25:30.290419 :C3B6A193:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 20:25:30.290464 :C3B6A19B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:35:31.168731 :C3B7663B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 20:35:31.652650 :C3B766A4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 20:35:31.647 2025-08-23 20:35:31.652653 :C3B766A5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21868 pso_num=24 pso_serial#=197 2025-08-23 20:35:31.652787 :C3B766A6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21868 osp_idx=22 osp_ver=776937 osp_pg=0 (spawn #518959) 2025-08-23 20:35:31.652788 :C3B766A7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 20:35:31.652788 :C3B766A8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=50 time=367033658 2025-08-23 20:35:31.652789 :C3B766A9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 20:35:31.652789 :C3B766AA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 20:35:31.652789 :C3B766AB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 20:35:31.652790 :C3B766AC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 20:35:31.652790 :C3B766AD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 20:35:31.653280 :C3B766AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 20:35:31.653324 :C3B766B5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:45:32.581421 :C3B82B4C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 20:45:32.945142 :C3B82BAD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 20:45:32.940 2025-08-23 20:45:32.945144 :C3B82BAE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21668 pso_num=24 pso_serial#=198 2025-08-23 20:45:32.945281 :C3B82BAF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21668 osp_idx=22 osp_ver=776938 osp_pg=0 (spawn #518983) 2025-08-23 20:45:32.945282 :C3B82BB0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 20:45:32.945282 :C3B82BB1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=51 time=367634955 2025-08-23 20:45:32.945283 :C3B82BB2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 20:45:32.945283 :C3B82BB3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 20:45:32.945283 :C3B82BB4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 20:45:32.945284 :C3B82BB5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 20:45:32.945284 :C3B82BB6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 20:45:32.945795 :C3B82BB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 20:45:32.945845 :C3B82BBE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:47:24.847648 :C3B85007:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 20:55:34.334732 :C3B8F0F3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 21:05:35.245338 :C3B9B810:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 21:05:35.577625 :C3B9B84D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 21:05:35.572 2025-08-23 21:05:35.577627 :C3B9B84E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22240 pso_num=24 pso_serial#=199 2025-08-23 21:05:35.577767 :C3B9B84F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22240 osp_idx=22 osp_ver=776939 osp_pg=0 (spawn #519030) 2025-08-23 21:05:35.577768 :C3B9B850:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 21:05:35.577768 :C3B9B851:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=52 time=368837580 2025-08-23 21:05:35.577768 :C3B9B852:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 21:05:35.577768 :C3B9B853:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 21:05:35.577769 :C3B9B854:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 21:05:35.577769 :C3B9B855:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 21:05:35.577769 :C3B9B856:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 21:05:35.578274 :C3B9B857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 21:05:35.578319 :C3B9B85E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 21:15:36.471540 :C3BA8240:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 21:15:36.914611 :C3BA82AB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 21:15:36.909 2025-08-23 21:15:36.914613 :C3BA82AC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21592 pso_num=24 pso_serial#=200 2025-08-23 21:15:36.914744 :C3BA82AD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21592 osp_idx=22 osp_ver=776940 osp_pg=0 (spawn #519053) 2025-08-23 21:15:36.914745 :C3BA82AE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 21:15:36.914745 :C3BA82AF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=53 time=369438924 2025-08-23 21:15:36.914746 :C3BA82B0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 21:15:36.914746 :C3BA82B1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 21:15:36.914746 :C3BA82B2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 21:15:36.914746 :C3BA82B3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 21:15:36.914747 :C3BA82B4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 21:15:36.915224 :C3BA82B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 21:15:36.915271 :C3BA82BB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 21:25:37.867853 :C3BB4AAF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 21:25:38.226682 :C3BB4B0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 21:25:38.221 2025-08-23 21:25:38.226684 :C3BB4B0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19868 pso_num=24 pso_serial#=201 2025-08-23 21:25:38.226820 :C3BB4B10:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19868 osp_idx=22 osp_ver=776941 osp_pg=0 (spawn #519076) 2025-08-23 21:25:38.226821 :C3BB4B11:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 21:25:38.226821 :C3BB4B12:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=54 time=370040237 2025-08-23 21:25:38.226821 :C3BB4B13:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 21:25:38.226822 :C3BB4B14:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 21:25:38.226822 :C3BB4B15:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 21:25:38.226823 :C3BB4B16:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 21:25:38.226823 :C3BB4B17:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 21:25:38.227309 :C3BB4B18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 21:25:38.227365 :C3BB4B1E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 21:35:39.141924 :C3BC0FF0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 21:35:39.396704 :C3BC102D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 21:35:39.391 2025-08-23 21:35:39.396706 :C3BC102E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20468 pso_num=24 pso_serial#=202 2025-08-23 21:35:39.396844 :C3BC102F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20468 osp_idx=22 osp_ver=776942 osp_pg=0 (spawn #519098) 2025-08-23 21:35:39.396845 :C3BC1030:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 21:35:39.396846 :C3BC1031:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=55 time=370641408 2025-08-23 21:35:39.396846 :C3BC1032:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 21:35:39.396847 :C3BC1033:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 21:35:39.396847 :C3BC1034:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 21:35:39.396847 :C3BC1035:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 21:35:39.396847 :C3BC1036:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 21:35:39.397369 :C3BC1037:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 21:35:39.397421 :C3BC1041:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 21:45:40.355920 :C3BCD480:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 21:45:40.720426 :C3BCD513:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 21:45:40.715 2025-08-23 21:45:40.720428 :C3BCD514:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21600 pso_num=24 pso_serial#=203 2025-08-23 21:45:40.720568 :C3BCD515:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21600 osp_idx=22 osp_ver=776943 osp_pg=0 (spawn #519121) 2025-08-23 21:45:40.720569 :C3BCD516:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 21:45:40.720569 :C3BCD517:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=56 time=371242737 2025-08-23 21:45:40.720570 :C3BCD518:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 21:45:40.720570 :C3BCD519:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 21:45:40.720570 :C3BCD51A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 21:45:40.720571 :C3BCD51B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 21:45:40.720571 :C3BCD51C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 21:45:40.721082 :C3BCD51D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 21:45:40.721125 :C3BCD524:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-23 21:55:41.592159 :C3BD994B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-23 21:55:41.893735 :C3BD99B8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-23 21:55:41.889 2025-08-23 21:55:41.893738 :C3BD99B9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21768 pso_num=24 pso_serial#=204 2025-08-23 21:55:41.893877 :C3BD99BA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21768 osp_idx=22 osp_ver=776944 osp_pg=0 (spawn #519144) 2025-08-23 21:55:41.893878 :C3BD99BB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 21:55:41.893878 :C3BD99BC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=57 time=371843908 2025-08-23 21:55:41.893879 :C3BD99BD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 21:55:41.893879 :C3BD99BE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 21:55:41.893879 :C3BD99BF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 21:55:41.893879 :C3BD99C0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 21:55:41.893880 :C3BD99C1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 21:55:41.894401 :C3BD99C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 21:55:41.894446 :C3BD99CA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 21768, W000)