Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250605082045\orcl_w000_14436_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:36922M/63366M, Ph+PgF:43630M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14436, image: ORACLE.EXE (W000) *** 2025-06-05 08:20:45.410 *** SESSION ID:(201.61041) 2025-06-05 08:20:45.410 *** 2025-06-05 08:20:45.410 Process diagnostic dump for ORACLE.EXE (W000), OS id=14436, pid: 25, proc_ser: 107, sid: 201, sess_ser: 61041 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=4.077865 sec, exc=4.077865 sec, total=4.077865 sec wait times: max=5.000000 sec, heur=4 min 19 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.000020 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=5.001705 sec, exc=5.001705 sec, total=5.001705 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=5.001265 sec, exc=5.001265 sec, total=5.001265 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.002397 sec, exc=5.002397 sec, total=5.002397 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=5.013512 sec, exc=5.013512 sec, total=5.013512 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=5.004638 sec, exc=5.004638 sec, total=5.004638 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=46 seq_num=47 snap_id=1 wait times: snap=5.014730 sec, exc=5.014730 sec, total=5.014730 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=5.014759 sec, exc=5.014759 sec, total=5.014759 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=5.010149 sec, exc=5.010149 sec, total=5.010149 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=5.009263 sec, exc=5.009263 sec, total=5.009263 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=5.013607 sec, exc=5.013607 sec, total=5.013607 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 201 serial 61041 --------------------------------------------------- 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, 08:18:45 - 08:20:45] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-05 08:20:45.410 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14436, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-05 06:48:50.358144 :A0860FA4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15904 pso_num=25 pso_serial#=93 2025-06-05 06:48:50.358283 :A0860FA5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15904 osp_idx=23 osp_ver=145045 osp_pg=0 (spawn #253709) 2025-06-05 06:48:50.358283 :A0860FA6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:48:50.358284 :A0860FA7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=2081889000 2025-06-05 06:48:50.358284 :A0860FA8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:48:50.358285 :A0860FA9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:48:50.358285 :A0860FAA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:48:50.358285 :A0860FAB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:48:50.358286 :A0860FAC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:48:50.358778 :A0860FAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:49:15.373199 :A086178D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 06:49:45.475773 :A08621CF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:50:15.477102 :A0862B69:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:50:15.466 2025-06-05 06:50:15.477106 :A0862B6A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15896 pso_num=25 pso_serial#=94 2025-06-05 06:50:15.477287 :A0862B6B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15896 osp_idx=23 osp_ver=145046 osp_pg=0 (spawn #253711) 2025-06-05 06:50:15.477287 :A0862B6C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:50:15.477288 :A0862B6D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=86 time=2081974125 2025-06-05 06:50:15.477288 :A0862B6E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:50:15.477288 :A0862B6F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:50:15.477289 :A0862B70:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:50:15.477289 :A0862B71:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:50:15.477289 :A0862B72:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:50:15.477779 :A0862B73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:50:45.584207 :A0863563:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:51:15.614891 :A0863F17:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:51:15.604 2025-06-05 06:51:15.614893 :A0863F18:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14992 pso_num=25 pso_serial#=95 2025-06-05 06:51:15.615028 :A0863F19:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14992 osp_idx=23 osp_ver=145047 osp_pg=0 (spawn #253714) 2025-06-05 06:51:15.615029 :A0863F1A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:51:15.615029 :A0863F1B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=87 time=2082034250 2025-06-05 06:51:15.615030 :A0863F1C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:51:15.615030 :A0863F1D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:51:15.615030 :A0863F1E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:51:15.615031 :A0863F1F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:51:15.615031 :A0863F20:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:51:15.615514 :A0863F21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:51:45.698145 :A08648EF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:52:15.705475 :A0865287:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:52:15.694 2025-06-05 06:52:15.705477 :A0865288:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15036 pso_num=25 pso_serial#=96 2025-06-05 06:52:15.705608 :A0865289:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15036 osp_idx=23 osp_ver=145048 osp_pg=0 (spawn #253716) 2025-06-05 06:52:15.705609 :A086528A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:52:15.705609 :A086528B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=2082094344 2025-06-05 06:52:15.705610 :A086528C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:52:15.705610 :A086528D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:52:15.705611 :A086528E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:52:15.705611 :A086528F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:52:15.705611 :A0865290:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:52:15.706091 :A0865291:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:52:45.771978 :A0865CB5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:53:15.756357 :A0866673:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:53:15.744 2025-06-05 06:53:15.756359 :A0866674:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15092 pso_num=25 pso_serial#=97 2025-06-05 06:53:15.756498 :A0866675:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15092 osp_idx=23 osp_ver=145049 osp_pg=0 (spawn #253718) 2025-06-05 06:53:15.756499 :A0866676:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:53:15.756499 :A0866677:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=2082154391 2025-06-05 06:53:15.756500 :A0866678:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:53:15.756500 :A0866679:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:53:15.756500 :A086667A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:53:15.756501 :A086667B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:53:15.756501 :A086667C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:53:15.757025 :A086667D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:53:45.787569 :A086705D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:54:15.819049 :A0867A29:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:54:15.808 2025-06-05 06:54:15.819051 :A0867A2A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12128 pso_num=25 pso_serial#=98 2025-06-05 06:54:15.819189 :A0867A2B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12128 osp_idx=23 osp_ver=145050 osp_pg=0 (spawn #253720) 2025-06-05 06:54:15.819190 :A0867A2C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:54:15.819190 :A0867A2D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=2082214454 2025-06-05 06:54:15.819190 :A0867A2E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:54:15.819191 :A0867A2F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:54:15.819191 :A0867A30:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:54:15.819191 :A0867A31:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:54:15.819192 :A0867A32:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:54:15.819681 :A0867A33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:54:45.881669 :A08684DA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:55:15.934986 :A0868E7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:55:15.924 2025-06-05 06:55:15.934987 :A0868E7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16072 pso_num=25 pso_serial#=99 2025-06-05 06:55:15.935122 :A0868E7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16072 osp_idx=23 osp_ver=145051 osp_pg=0 (spawn #253722) 2025-06-05 06:55:15.935123 :A0868E7E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:55:15.935124 :A0868E7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=2082274579 2025-06-05 06:55:15.935124 :A0868E80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:55:15.935125 :A0868E81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:55:15.935125 :A0868E82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:55:15.935125 :A0868E83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:55:15.935126 :A0868E84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:55:15.935594 :A0868E85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:55:45.973184 :A0869832:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 06:56:16.199190 :A086A274:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 06:56:16.195 2025-06-05 06:56:16.199192 :A086A275:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16000 pso_num=25 pso_serial#=100 2025-06-05 06:56:16.199333 :A086A276:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16000 osp_idx=23 osp_ver=145052 osp_pg=0 (spawn #253725) 2025-06-05 06:56:16.199334 :A086A277:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 06:56:16.199334 :A086A278:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=2082334844 2025-06-05 06:56:16.199335 :A086A279:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 06:56:16.199335 :A086A27A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 06:56:16.199335 :A086A27B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 06:56:16.199336 :A086A27C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 06:56:16.199336 :A086A27D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 06:56:16.199839 :A086A27E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 06:56:16.199885 :A086A285:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 07:02:09.269584 :A08718CA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-05 07:06:17.426379 :A08771A9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-05 07:16:18.132457 :A088378B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 07:16:18.621365 :A08837C6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 07:16:18.616 2025-06-05 07:16:18.621367 :A08837C7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12492 pso_num=25 pso_serial#=101 2025-06-05 07:16:18.621504 :A08837C8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12492 osp_idx=23 osp_ver=145053 osp_pg=0 (spawn #253772) 2025-06-05 07:16:18.621504 :A08837C9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 07:16:18.621505 :A08837CA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=2083537266 2025-06-05 07:16:18.621505 :A08837CB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 07:16:18.621505 :A08837CC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 07:16:18.621506 :A08837CD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 07:16:18.621506 :A08837CE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 07:16:18.621507 :A08837CF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 07:16:18.621996 :A08837D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 07:16:18.622043 :A08837D7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 07:26:19.362350 :A08900F8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 07:26:19.882601 :A0890137:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 07:26:19.878 2025-06-05 07:26:19.882602 :A0890138:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16048 pso_num=25 pso_serial#=102 2025-06-05 07:26:19.882742 :A0890139:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16048 osp_idx=23 osp_ver=145054 osp_pg=0 (spawn #253795) 2025-06-05 07:26:19.882742 :A089013A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 07:26:19.882743 :A089013B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=2084138532 2025-06-05 07:26:19.882743 :A089013C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 07:26:19.882743 :A089013D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 07:26:19.882744 :A089013E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 07:26:19.882744 :A089013F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 07:26:19.882744 :A0890140:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 07:26:19.883300 :A0890141:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 07:26:19.883344 :A089014A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 07:36:20.721826 :A089C855:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 07:36:21.148408 :A089C895:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 07:36:21.144 2025-06-05 07:36:21.148411 :A089C896:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14884 pso_num=25 pso_serial#=103 2025-06-05 07:36:21.148545 :A089C897:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14884 osp_idx=23 osp_ver=145055 osp_pg=0 (spawn #253818) 2025-06-05 07:36:21.148546 :A089C898:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 07:36:21.148546 :A089C899:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=2084739797 2025-06-05 07:36:21.148546 :A089C89A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 07:36:21.148547 :A089C89B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 07:36:21.148547 :A089C89C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 07:36:21.148547 :A089C89D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 07:36:21.148548 :A089C89E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 07:36:21.149023 :A089C89F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 07:36:21.149067 :A089C8A7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 07:46:21.952070 :A08A8F7C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 07:46:22.284980 :A08A8FB8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 07:46:22.280 2025-06-05 07:46:22.284982 :A08A8FB9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16284 pso_num=25 pso_serial#=104 2025-06-05 07:46:22.285123 :A08A8FBA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16284 osp_idx=23 osp_ver=145056 osp_pg=0 (spawn #253840) 2025-06-05 07:46:22.285124 :A08A8FBB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 07:46:22.285125 :A08A8FBC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=2085340938 2025-06-05 07:46:22.285126 :A08A8FBD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 07:46:22.285126 :A08A8FBE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 07:46:22.285126 :A08A8FBF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 07:46:22.285127 :A08A8FC0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 07:46:22.285127 :A08A8FC1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 07:46:22.285630 :A08A8FC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 07:46:22.285682 :A08A8FCA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 07:56:23.141057 :A08B564F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 07:56:23.520870 :A08B5694:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 07:56:23.516 2025-06-05 07:56:23.520872 :A08B5695:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9628 pso_num=25 pso_serial#=105 2025-06-05 07:56:23.521016 :A08B5696:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9628 osp_idx=23 osp_ver=145057 osp_pg=0 (spawn #253863) 2025-06-05 07:56:23.521017 :A08B5697:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 07:56:23.521017 :A08B5698:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=2085942172 2025-06-05 07:56:23.521018 :A08B5699:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 07:56:23.521018 :A08B569A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 07:56:23.521018 :A08B569B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 07:56:23.521019 :A08B569C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 07:56:23.521019 :A08B569D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 07:56:23.521526 :A08B569E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 07:56:23.521572 :A08B56A5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 08:06:24.377615 :A08C255E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 08:06:24.741736 :A08C2593:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 08:06:24.736 2025-06-05 08:06:24.741738 :A08C2594:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11852 pso_num=25 pso_serial#=106 2025-06-05 08:06:24.741879 :A08C2595:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11852 osp_idx=23 osp_ver=145058 osp_pg=0 (spawn #253889) 2025-06-05 08:06:24.741880 :A08C2596:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:06:24.741880 :A08C2597:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=2086543391 2025-06-05 08:06:24.741880 :A08C2598:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:06:24.741881 :A08C2599:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:06:24.741881 :A08C259A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:06:24.741881 :A08C259B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:06:24.741882 :A08C259C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:06:24.742389 :A08C259D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 08:06:24.742434 :A08C25A5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-05 08:16:25.690707 :A08CED65:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-05 08:16:25.960904 :A08CEDA3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-05 08:16:25.956 2025-06-05 08:16:25.960907 :A08CEDA4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14436 pso_num=25 pso_serial#=107 2025-06-05 08:16:25.961045 :A08CEDA5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14436 osp_idx=23 osp_ver=145059 osp_pg=0 (spawn #253911) 2025-06-05 08:16:25.961045 :A08CEDA6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:16:25.961046 :A08CEDA7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=2087144610 2025-06-05 08:16:25.961046 :A08CEDA8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:16:25.961046 :A08CEDA9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:16:25.961047 :A08CEDAA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:16:25.961047 :A08CEDAB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:16:25.961047 :A08CEDAC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:16:25.961544 :A08CEDAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-05 08:16:25.961591 :A08CEDB5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 14436, W000)