Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622060006\orcl_w000_15604_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622060006\orcl_w000_15604_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:36386M/63366M, Ph+PgF:42645M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15604, image: ORACLE.EXE (W000) *** 2025-06-22 06:00:06.601 *** SESSION ID:(177.18717) 2025-06-22 06:00:06.601 *** 2025-06-22 06:00:06.601 Process diagnostic dump for ORACLE.EXE (W000), OS id=15604, pid: 22, proc_ser: 52, sid: 177, sess_ser: 18717 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=3.570082 sec, exc=3.570082 sec, total=3.570082 sec wait times: max=5.000000 sec, heur=4 min 43 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.008919 sec, exc=5.008919 sec, total=5.008919 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=55 seq_num=56 snap_id=1 wait times: snap=5.000389 sec, exc=5.000389 sec, total=5.000389 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.001938 sec, exc=5.001938 sec, total=5.001938 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.014712 sec, exc=5.014712 sec, total=5.014712 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=52 seq_num=53 snap_id=1 wait times: snap=5.010139 sec, exc=5.010139 sec, total=5.010139 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 5: 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.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.000022 sec of elapsed time 6: 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.014719 sec, exc=5.014719 sec, total=5.014719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 7: 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.007229 sec, exc=5.007229 sec, total=5.007229 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: 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.001309 sec, exc=5.001309 sec, total=5.001309 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: 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.000592 sec, exc=5.000592 sec, total=5.000592 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 18717 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 05:58:06 - 06:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-22 06:00:06.601 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15604, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-22 03:35:04.953579 :A7F013FA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 03:35:04.949 2025-06-22 03:35:04.953581 :A7F013FB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13744 pso_num=22 pso_serial#=39 2025-06-22 03:35:04.953714 :A7F013FC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13744 osp_idx=0 osp_ver=337986 osp_pg=0 (spawn #309937) 2025-06-22 03:35:04.953715 :A7F013FD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 03:35:04.953715 :A7F013FE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=143 time=3539048141 2025-06-22 03:35:04.953716 :A7F013FF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 03:35:04.953716 :A7F01400:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 03:35:04.953717 :A7F01401:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 03:35:04.953717 :A7F01402:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 03:35:04.953717 :A7F01403:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 03:35:04.954221 :A7F01404: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-06-22 03:35:04.954265 :A7F0140C: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-06-22 03:45:05.787274 :A7F0D9E8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 03:45:06.135874 :A7F0DA20:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 03:45:06.130 2025-06-22 03:45:06.135876 :A7F0DA21:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18296 pso_num=22 pso_serial#=40 2025-06-22 03:45:06.136024 :A7F0DA22:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18296 osp_idx=0 osp_ver=337987 osp_pg=0 (spawn #309961) 2025-06-22 03:45:06.136025 :A7F0DA23:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 03:45:06.136025 :A7F0DA24:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=144 time=3539649329 2025-06-22 03:45:06.136025 :A7F0DA25:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 03:45:06.136025 :A7F0DA26:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 03:45:06.136025 :A7F0DA27:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 03:45:06.136026 :A7F0DA28:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 03:45:06.136026 :A7F0DA29:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 03:45:06.136538 :A7F0DA2A: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-06-22 03:45:06.136583 :A7F0DA32: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-06-22 03:55:07.029452 :A7F1A039:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 03:55:07.424791 :A7F1A07D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 03:55:07.419 2025-06-22 03:55:07.424793 :A7F1A07E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16448 pso_num=22 pso_serial#=41 2025-06-22 03:55:07.424935 :A7F1A07F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16448 osp_idx=0 osp_ver=337988 osp_pg=0 (spawn #309984) 2025-06-22 03:55:07.424936 :A7F1A080:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 03:55:07.424936 :A7F1A081:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=145 time=3540250610 2025-06-22 03:55:07.424937 :A7F1A082:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 03:55:07.424937 :A7F1A083:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 03:55:07.424937 :A7F1A084:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 03:55:07.424938 :A7F1A085:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 03:55:07.424938 :A7F1A086:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 03:55:07.425455 :A7F1A087: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-06-22 03:55:07.425501 :A7F1A091: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-06-22 04:05:08.247954 :A7F267B4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 04:05:08.740448 :A7F267EF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 04:05:08.735 2025-06-22 04:05:08.740450 :A7F267F0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8776 pso_num=22 pso_serial#=42 2025-06-22 04:05:08.740589 :A7F267F1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8776 osp_idx=0 osp_ver=337989 osp_pg=0 (spawn #310009) 2025-06-22 04:05:08.740589 :A7F267F2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 04:05:08.740590 :A7F267F3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=146 time=3540851938 2025-06-22 04:05:08.740590 :A7F267F4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 04:05:08.740590 :A7F267F5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 04:05:08.740591 :A7F267F6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 04:05:08.740591 :A7F267F7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 04:05:08.740591 :A7F267F8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 04:05:08.741094 :A7F267F9: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-06-22 04:05:08.741138 :A7F26802: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-06-22 04:15:09.644381 :A7F3329A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 04:15:09.992743 :A7F332F6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 04:15:09.988 2025-06-22 04:15:09.992745 :A7F332F7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16424 pso_num=22 pso_serial#=43 2025-06-22 04:15:09.992885 :A7F332F8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16424 osp_idx=0 osp_ver=337990 osp_pg=0 (spawn #310032) 2025-06-22 04:15:09.992886 :A7F332F9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 04:15:09.992886 :A7F332FA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=147 time=3541453188 2025-06-22 04:15:09.992887 :A7F332FB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 04:15:09.992887 :A7F332FC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 04:15:09.992888 :A7F332FD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 04:15:09.992888 :A7F332FE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 04:15:09.992888 :A7F332FF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 04:15:09.993387 :A7F33300: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-06-22 04:15:09.993436 :A7F33307: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-06-22 04:25:10.886670 :A7F3FAC6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 04:25:11.219579 :A7F3FAFF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 04:25:11.214 2025-06-22 04:25:11.219581 :A7F3FB00:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9900 pso_num=22 pso_serial#=44 2025-06-22 04:25:11.219721 :A7F3FB01:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9900 osp_idx=0 osp_ver=337991 osp_pg=0 (spawn #310055) 2025-06-22 04:25:11.219722 :A7F3FB02:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 04:25:11.219722 :A7F3FB03:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=148 time=3542054407 2025-06-22 04:25:11.219722 :A7F3FB04:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 04:25:11.219723 :A7F3FB05:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 04:25:11.219723 :A7F3FB06:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 04:25:11.219724 :A7F3FB07:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 04:25:11.219724 :A7F3FB08:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 04:25:11.220208 :A7F3FB09: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-06-22 04:25:11.220252 :A7F3FB11: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-06-22 04:35:11.995069 :A7F4BF2A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 04:35:12.453055 :A7F4BFA5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 04:35:12.448 2025-06-22 04:35:12.453057 :A7F4BFA6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15256 pso_num=22 pso_serial#=45 2025-06-22 04:35:12.453194 :A7F4BFA7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15256 osp_idx=0 osp_ver=337992 osp_pg=0 (spawn #310077) 2025-06-22 04:35:12.453195 :A7F4BFA8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 04:35:12.453195 :A7F4BFA9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=149 time=3542655641 2025-06-22 04:35:12.453196 :A7F4BFAA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 04:35:12.453196 :A7F4BFAB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 04:35:12.453197 :A7F4BFAC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 04:35:12.453197 :A7F4BFAD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 04:35:12.453197 :A7F4BFAE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 04:35:12.453703 :A7F4BFAF: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-06-22 04:35:12.453746 :A7F4BFB7: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-06-22 04:45:13.272576 :A7F584D0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 04:45:13.724922 :A7F58547:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 04:45:13.720 2025-06-22 04:45:13.724924 :A7F58548:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12128 pso_num=22 pso_serial#=46 2025-06-22 04:45:13.725066 :A7F58549:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12128 osp_idx=0 osp_ver=337993 osp_pg=0 (spawn #310100) 2025-06-22 04:45:13.725067 :A7F5854A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 04:45:13.725068 :A7F5854B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=150 time=3543256922 2025-06-22 04:45:13.725068 :A7F5854C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 04:45:13.725069 :A7F5854D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 04:45:13.725069 :A7F5854E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 04:45:13.725069 :A7F5854F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 04:45:13.725070 :A7F58550:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 04:45:13.725564 :A7F58551: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-06-22 04:45:13.725612 :A7F58559: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-06-22 04:55:14.605828 :A7F64AF7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 04:55:15.064078 :A7F64B3E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 04:55:15.059 2025-06-22 04:55:15.064080 :A7F64B3F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15576 pso_num=22 pso_serial#=47 2025-06-22 04:55:15.064220 :A7F64B40:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15576 osp_idx=0 osp_ver=337994 osp_pg=0 (spawn #310123) 2025-06-22 04:55:15.064220 :A7F64B41:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 04:55:15.064221 :A7F64B42:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=151 time=3543858250 2025-06-22 04:55:15.064221 :A7F64B43:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 04:55:15.064221 :A7F64B44:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 04:55:15.064222 :A7F64B45:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 04:55:15.064222 :A7F64B46:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 04:55:15.064222 :A7F64B47:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 04:55:15.064719 :A7F64B48: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-06-22 04:55:15.064768 :A7F64B50: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-06-22 05:04:24.612247 :A7F700E4: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-06-22 05:04:24.614444 :A7F700F6: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-06-22 05:05:16.356451 :A7F712CC: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-06-22 05:15:17.231722 :A7F7E0A3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 05:15:17.720681 :A7F7E0E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 05:15:17.715 2025-06-22 05:15:17.720683 :A7F7E0E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18008 pso_num=22 pso_serial#=48 2025-06-22 05:15:17.720822 :A7F7E0E9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18008 osp_idx=0 osp_ver=337995 osp_pg=0 (spawn #310170) 2025-06-22 05:15:17.720823 :A7F7E0EA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:15:17.720823 :A7F7E0EB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=152 time=3545060907 2025-06-22 05:15:17.720823 :A7F7E0EC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:15:17.720824 :A7F7E0ED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:15:17.720824 :A7F7E0EE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:15:17.720824 :A7F7E0EF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:15:17.720825 :A7F7E0F0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:15:17.721315 :A7F7E0F1: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-06-22 05:15:17.721361 :A7F7E0F9: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-06-22 05:25:18.554219 :A7F8A5C8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 05:25:18.980823 :A7F8A60C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 05:25:18.976 2025-06-22 05:25:18.980825 :A7F8A60D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17960 pso_num=22 pso_serial#=49 2025-06-22 05:25:18.980959 :A7F8A60E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17960 osp_idx=0 osp_ver=337996 osp_pg=0 (spawn #310193) 2025-06-22 05:25:18.980960 :A7F8A60F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:25:18.980960 :A7F8A610:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=153 time=3545662172 2025-06-22 05:25:18.980960 :A7F8A611:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:25:18.980961 :A7F8A612:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:25:18.980961 :A7F8A613:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:25:18.980961 :A7F8A614:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:25:18.980962 :A7F8A615:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:25:18.981482 :A7F8A616: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-06-22 05:25:18.981529 :A7F8A61E: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-06-22 05:35:19.836288 :A7F96C9B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 05:35:20.200275 :A7F96CE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 05:35:20.195 2025-06-22 05:35:20.200277 :A7F96CE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17304 pso_num=22 pso_serial#=50 2025-06-22 05:35:20.200413 :A7F96CE2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17304 osp_idx=0 osp_ver=337997 osp_pg=0 (spawn #310215) 2025-06-22 05:35:20.200414 :A7F96CE3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:35:20.200414 :A7F96CE4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=154 time=3546263391 2025-06-22 05:35:20.200415 :A7F96CE5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:35:20.200415 :A7F96CE6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:35:20.200415 :A7F96CE7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:35:20.200416 :A7F96CE8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:35:20.200416 :A7F96CE9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:35:20.200902 :A7F96CEA: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-06-22 05:35:20.200946 :A7F96CF2: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-06-22 05:35:35.213103 :A7F971EB:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-06-22 05:35:35.213123 :A7F971F0:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-06-22 05:35:35.213125 :A7F971F1:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=20, exc=20, tot=20 2025-06-22 05:45:21.057850 :A7FA3125:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 05:45:21.453356 :A7FA3169:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 05:45:21.448 2025-06-22 05:45:21.453357 :A7FA316A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17916 pso_num=22 pso_serial#=51 2025-06-22 05:45:21.453501 :A7FA316B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17916 osp_idx=0 osp_ver=337998 osp_pg=0 (spawn #310238) 2025-06-22 05:45:21.453501 :A7FA316C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:45:21.453502 :A7FA316D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=155 time=3546864641 2025-06-22 05:45:21.453502 :A7FA316E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:45:21.453502 :A7FA316F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:45:21.453503 :A7FA3170:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:45:21.453503 :A7FA3171:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:45:21.453503 :A7FA3172:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:45:21.454007 :A7FA3173: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-06-22 05:45:21.454051 :A7FA317B: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-06-22 05:55:22.342721 :A7FAF614:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-22 05:55:22.613067 :A7FAF658:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-22 05:55:22.608 2025-06-22 05:55:22.613069 :A7FAF659:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15604 pso_num=22 pso_serial#=52 2025-06-22 05:55:22.613213 :A7FAF65A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15604 osp_idx=0 osp_ver=337999 osp_pg=0 (spawn #310261) 2025-06-22 05:55:22.613214 :A7FAF65B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:55:22.613214 :A7FAF65C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=156 time=3547465797 2025-06-22 05:55:22.613215 :A7FAF65D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:55:22.613215 :A7FAF65E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:55:22.613216 :A7FAF65F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:55:22.613216 :A7FAF660:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:55:22.613216 :A7FAF661:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:55:22.613709 :A7FAF662: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-06-22 05:55:22.613756 :A7FAF669:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15604, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de