Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524060008\orcl_w000_13080_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524060008\orcl_w000_13080_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:37295M/63366M, Ph+PgF:44315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 13080, image: ORACLE.EXE (W000) *** 2025-05-24 06:00:08.802 *** SESSION ID:(177.7443) 2025-05-24 06:00:08.802 *** 2025-05-24 06:00:08.802 Process diagnostic dump for ORACLE.EXE (W000), OS id=13080, pid: 22, proc_ser: 53, sid: 177, sess_ser: 7443 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=144 seq_num=145 snap_id=1 wait times: snap=1.458829 sec, exc=1.458829 sec, total=1.458829 sec wait times: max=5.000000 sec, heur=11 min 35 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=143 seq_num=144 snap_id=1 wait times: snap=5.009203 sec, exc=5.009203 sec, total=5.009203 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=142 seq_num=143 snap_id=1 wait times: snap=5.014723 sec, exc=5.014723 sec, total=5.014723 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=141 seq_num=142 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=140 seq_num=141 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.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=139 seq_num=140 snap_id=1 wait times: snap=5.001128 sec, exc=5.001128 sec, total=5.001128 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=138 seq_num=139 snap_id=1 wait times: snap=5.000631 sec, exc=5.000631 sec, total=5.000631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=137 seq_num=138 snap_id=1 wait times: snap=5.002018 sec, exc=5.002018 sec, total=5.002018 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=136 seq_num=137 snap_id=1 wait times: snap=5.015248 sec, exc=5.015248 sec, total=5.015248 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=135 seq_num=136 snap_id=1 wait times: snap=5.004584 sec, exc=5.004584 sec, total=5.004584 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000063 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=134 seq_num=135 snap_id=1 wait times: snap=5.009163 sec, exc=5.009163 sec, total=5.009163 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000080 sec of elapsed time Sampled Session History of session 177 serial 7443 --------------------------------------------------- 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:08 - 06:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-24 06:00:08.802 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 13080, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-24 03:28:02.561079 :9B32704F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13992 pso_num=22 pso_serial#=40 2025-05-24 03:28:02.561224 :9B327050:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13992 osp_idx=0 osp_ver=228265 osp_pg=0 (spawn #213260) 2025-05-24 03:28:02.561224 :9B327051:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 03:28:02.561225 :9B327052:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=249 time=1033054813 2025-05-24 03:28:02.561225 :9B327053:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 03:28:02.561226 :9B327054:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 03:28:02.561226 :9B327055:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 03:28:02.561227 :9B327056:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 03:28:02.561227 :9B327057:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 03:28:02.561729 :9B327058:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 03:28:02.561775 :9B32705F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 03:38:03.506953 :9B3333F1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 03:38:03.964851 :9B333452:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 03:38:03.960 2025-05-24 03:38:03.964853 :9B333453:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8780 pso_num=22 pso_serial#=41 2025-05-24 03:38:03.964992 :9B333454:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8780 osp_idx=0 osp_ver=228266 osp_pg=0 (spawn #213282) 2025-05-24 03:38:03.964993 :9B333455:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 03:38:03.964993 :9B333456:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=250 time=1033656219 2025-05-24 03:38:03.964993 :9B333457:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 03:38:03.964994 :9B333458:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 03:38:03.964994 :9B333459:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 03:38:03.964994 :9B33345A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 03:38:03.964995 :9B33345B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 03:38:03.965494 :9B33345C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 03:38:03.965543 :9B333466:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 03:48:04.898947 :9B33F7C2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 03:48:05.263278 :9B33F816:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 03:48:05.258 2025-05-24 03:48:05.263281 :9B33F817:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13268 pso_num=22 pso_serial#=42 2025-05-24 03:48:05.263419 :9B33F818:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13268 osp_idx=0 osp_ver=228267 osp_pg=0 (spawn #213305) 2025-05-24 03:48:05.263420 :9B33F819:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 03:48:05.263420 :9B33F81A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=251 time=1034257516 2025-05-24 03:48:05.263421 :9B33F81B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 03:48:05.263421 :9B33F81C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 03:48:05.263422 :9B33F81D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 03:48:05.263422 :9B33F81E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 03:48:05.263422 :9B33F81F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 03:48:05.263923 :9B33F820:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 03:48:05.263973 :9B33F829:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 03:48:32.805003 :9B3400CF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-24 03:58:06.667862 :9B34BD0C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-24 04:08:07.654985 :9B3588AC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 04:08:08.018994 :9B358911:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 04:08:08.014 2025-05-24 04:08:08.018997 :9B358912:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9748 pso_num=22 pso_serial#=43 2025-05-24 04:08:08.019132 :9B358913:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9748 osp_idx=0 osp_ver=228268 osp_pg=0 (spawn #213352) 2025-05-24 04:08:08.019133 :9B358914:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 04:08:08.019133 :9B358915:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=252 time=1035460266 2025-05-24 04:08:08.019134 :9B358916:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 04:08:08.019134 :9B358917:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 04:08:08.019134 :9B358918:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 04:08:08.019135 :9B358919:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 04:08:08.019135 :9B35891A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 04:08:08.019610 :9B35891B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 04:08:08.019657 :9B358925:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 04:18:08.926430 :9B365085:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 04:18:09.274832 :9B3650EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 04:18:09.270 2025-05-24 04:18:09.274834 :9B3650EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12920 pso_num=22 pso_serial#=44 2025-05-24 04:18:09.274970 :9B3650F0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12920 osp_idx=0 osp_ver=228269 osp_pg=0 (spawn #213375) 2025-05-24 04:18:09.274970 :9B3650F1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 04:18:09.274971 :9B3650F2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=1036061532 2025-05-24 04:18:09.274971 :9B3650F3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 04:18:09.274971 :9B3650F4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 04:18:09.274972 :9B3650F5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 04:18:09.274972 :9B3650F6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 04:18:09.274972 :9B3650F7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 04:18:09.275457 :9B3650F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 04:18:09.275502 :9B365100:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 04:28:10.221939 :9B371406:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 04:28:10.523511 :9B371464:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 04:28:10.519 2025-05-24 04:28:10.523514 :9B371465:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10200 pso_num=22 pso_serial#=45 2025-05-24 04:28:10.523645 :9B371466:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10200 osp_idx=0 osp_ver=228270 osp_pg=0 (spawn #213398) 2025-05-24 04:28:10.523645 :9B371467:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 04:28:10.523646 :9B371468:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=1036662782 2025-05-24 04:28:10.523646 :9B371469:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 04:28:10.523646 :9B37146A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 04:28:10.523647 :9B37146B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 04:28:10.523647 :9B37146C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 04:28:10.523648 :9B37146D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 04:28:10.524121 :9B37146E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 04:28:10.524164 :9B371476:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 04:38:11.410726 :9B37D809:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 04:38:11.728089 :9B37D887:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 04:38:11.723 2025-05-24 04:38:11.728091 :9B37D888:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9780 pso_num=22 pso_serial#=46 2025-05-24 04:38:11.728229 :9B37D889:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9780 osp_idx=0 osp_ver=228271 osp_pg=0 (spawn #213420) 2025-05-24 04:38:11.728230 :9B37D88A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 04:38:11.728230 :9B37D88B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=1037263985 2025-05-24 04:38:11.728230 :9B37D88C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 04:38:11.728231 :9B37D88D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 04:38:11.728231 :9B37D88E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 04:38:11.728231 :9B37D88F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 04:38:11.728232 :9B37D890:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 04:38:11.728716 :9B37D891:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 04:38:11.728762 :9B37D89B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 04:38:56.822982 :9B37E83E:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=10 seq_num=11 snap_id=1 2025-05-24 04:38:56.823009 :9B37E84C:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=10 seq_num=11 snap_id=1 2025-05-24 04:38:56.823011 :9B37E84D:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=27, exc=27, tot=27 2025-05-24 04:48:12.715069 :9B389CD0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 04:48:13.126136 :9B389D36:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 04:48:13.121 2025-05-24 04:48:13.126138 :9B389D37:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8352 pso_num=22 pso_serial#=47 2025-05-24 04:48:13.126277 :9B389D38:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8352 osp_idx=0 osp_ver=228272 osp_pg=0 (spawn #213443) 2025-05-24 04:48:13.126278 :9B389D39:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 04:48:13.126278 :9B389D3A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=1037865375 2025-05-24 04:48:13.126279 :9B389D3B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 04:48:13.126279 :9B389D3C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 04:48:13.126279 :9B389D3D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 04:48:13.126280 :9B389D3E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 04:48:13.126280 :9B389D3F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 04:48:13.126780 :9B389D40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 04:48:13.126824 :9B389D46:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 04:58:14.079930 :9B39642F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 04:58:14.381827 :9B39648A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 04:58:14.377 2025-05-24 04:58:14.381829 :9B39648B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11856 pso_num=22 pso_serial#=48 2025-05-24 04:58:14.381976 :9B39648C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11856 osp_idx=0 osp_ver=228273 osp_pg=0 (spawn #213466) 2025-05-24 04:58:14.381977 :9B39648D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 04:58:14.381977 :9B39648E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=1038466641 2025-05-24 04:58:14.381978 :9B39648F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 04:58:14.381979 :9B396490:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 04:58:14.381979 :9B396491:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 04:58:14.381979 :9B396492:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 04:58:14.381980 :9B396493:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 04:58:14.382506 :9B396494:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 04:58:14.382551 :9B39649E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:08:15.365580 :9B3A301F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 05:08:15.620223 :9B3A3077:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 05:08:15.615 2025-05-24 05:08:15.620226 :9B3A3078:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14084 pso_num=22 pso_serial#=49 2025-05-24 05:08:15.620363 :9B3A3079:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14084 osp_idx=0 osp_ver=228274 osp_pg=0 (spawn #213491) 2025-05-24 05:08:15.620363 :9B3A307A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:08:15.620364 :9B3A307B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=1039067875 2025-05-24 05:08:15.620364 :9B3A307C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:08:15.620365 :9B3A307D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:08:15.620365 :9B3A307E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:08:15.620366 :9B3A307F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:08:15.620366 :9B3A3080:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:08:15.620877 :9B3A3081:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:08:15.620925 :9B3A3088:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:18:16.531068 :9B3AF7EC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 05:18:16.910708 :9B3AF846:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 05:18:16.905 2025-05-24 05:18:16.910710 :9B3AF847:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13720 pso_num=22 pso_serial#=50 2025-05-24 05:18:16.910846 :9B3AF848:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13720 osp_idx=0 osp_ver=228275 osp_pg=0 (spawn #213514) 2025-05-24 05:18:16.910847 :9B3AF849:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:18:16.910847 :9B3AF84A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=1039669157 2025-05-24 05:18:16.910848 :9B3AF84B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:18:16.910848 :9B3AF84C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:18:16.910848 :9B3AF84D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:18:16.910849 :9B3AF84E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:18:16.910849 :9B3AF84F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:18:16.911340 :9B3AF850:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:18:16.911384 :9B3AF859:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:28:18.002776 :9B3BBCB9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 05:28:18.210597 :9B3BBD0A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 05:28:18.205 2025-05-24 05:28:18.210599 :9B3BBD0B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12772 pso_num=22 pso_serial#=51 2025-05-24 05:28:18.210745 :9B3BBD0C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12772 osp_idx=0 osp_ver=228276 osp_pg=0 (spawn #213537) 2025-05-24 05:28:18.210746 :9B3BBD0D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:28:18.210746 :9B3BBD0E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=1040270469 2025-05-24 05:28:18.210747 :9B3BBD0F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:28:18.210747 :9B3BBD10:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:28:18.210748 :9B3BBD11:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:28:18.210748 :9B3BBD12:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:28:18.210749 :9B3BBD13:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:28:18.211241 :9B3BBD14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:28:18.211290 :9B3BBD1E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:38:19.220091 :9B3C8154:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 05:38:19.521863 :9B3C81B8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 05:38:19.517 2025-05-24 05:38:19.521865 :9B3C81B9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14020 pso_num=22 pso_serial#=52 2025-05-24 05:38:19.522000 :9B3C81BA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14020 osp_idx=0 osp_ver=228277 osp_pg=0 (spawn #213559) 2025-05-24 05:38:19.522001 :9B3C81BB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:38:19.522001 :9B3C81BC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=1040871782 2025-05-24 05:38:19.522001 :9B3C81BD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:38:19.522002 :9B3C81BE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:38:19.522002 :9B3C81BF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:38:19.522002 :9B3C81C0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:38:19.522003 :9B3C81C1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:38:19.522559 :9B3C81C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:38:19.522602 :9B3C81CA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:48:20.484572 :9B3D462E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-24 05:48:20.838157 :9B3D4681:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-24 05:48:20.833 2025-05-24 05:48:20.838159 :9B3D4682:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13080 pso_num=22 pso_serial#=53 2025-05-24 05:48:20.838296 :9B3D4683:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13080 osp_idx=0 osp_ver=228278 osp_pg=0 (spawn #213580) 2025-05-24 05:48:20.838297 :9B3D4684:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:48:20.838297 :9B3D4685:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=1041473094 2025-05-24 05:48:20.838298 :9B3D4686:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:48:20.838298 :9B3D4687:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:48:20.838298 :9B3D4688:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:48:20.838299 :9B3D4689:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:48:20.838299 :9B3D468A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:48:20.838798 :9B3D468B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:48:20.838842 :9B3D4693:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:48:33.488619 :9B3D4A87:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-24 05:58:22.175773 :9B3E0B45:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 13080, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de