Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250505220008\orcl_w000_10932_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250505220008\orcl_w000_10932_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:37453M/63366M, Ph+PgF:44909M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 10932, image: ORACLE.EXE (W000) *** 2025-05-05 22:00:08.938 *** SESSION ID:(177.44529) 2025-05-05 22:00:08.938 *** 2025-05-05 22:00:08.938 Process diagnostic dump for ORACLE.EXE (W000), OS id=10932, pid: 22, proc_ser: 2, sid: 177, sess_ser: 44529 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=4.152182 sec, exc=4.152182 sec, total=4.152182 sec wait times: max=5.000000 sec, heur=44.222846 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.000026 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.009728 sec, exc=5.009728 sec, total=5.009728 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=7 seq_num=8 snap_id=1 wait times: snap=5.011198 sec, exc=5.011198 sec, total=5.011198 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=6 seq_num=7 snap_id=1 wait times: snap=5.012275 sec, exc=5.012275 sec, total=5.012275 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=5 seq_num=6 snap_id=1 wait times: snap=5.014732 sec, exc=5.014732 sec, total=5.014732 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.014740 sec, exc=5.014740 sec, total=5.014740 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=5.004610 sec, exc=5.004610 sec, total=5.004610 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=5.004617 sec, exc=5.004617 sec, total=5.004617 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=4.998573 sec, exc=4.998573 sec, total=4.998573 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.003966 sec of elapsed time 8: waited for 'class slave wait' slave id=0x11f4fab0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 177 serial 44529 --------------------------------------------------- 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 --------------------------------------------------- [45 samples, 21:59:24 - 22:00:08] idle wait at each sample [session created at: 21:59:24] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-05 22:00:08.938 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 10932, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-05 19:29:04.311058 :93250886:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2936 pso_num=22 pso_serial#=244 2025-05-05 19:29:04.311191 :93250887:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=2936 osp_idx=0 osp_ver=160129 osp_pg=0 (spawn #151769) 2025-05-05 19:29:04.311192 :93250888: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-05 19:29:04.311192 :93250889:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=3744121484 2025-05-05 19:29:04.311193 :9325088A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 19:29:04.311194 :9325088B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 19:29:04.311194 :9325088C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 19:29:04.311195 :9325088D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 19:29:04.311195 :9325088E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 19:29:04.311688 :9325088F: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-05 19:29:04.311737 :93250897: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-05 19:35:36.960721 :93258D03: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-05 19:39:05.550483 :9325D1F4: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-05 19:49:06.535749 :93269AE5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 19:49:06.846865 :93269B1E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 19:49:06.842 2025-05-05 19:49:06.846867 :93269B1F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9552 pso_num=22 pso_serial#=245 2025-05-05 19:49:06.847000 :93269B20:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9552 osp_idx=0 osp_ver=160130 osp_pg=0 (spawn #151813) 2025-05-05 19:49:06.847001 :93269B21: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-05 19:49:06.847001 :93269B22:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=3745324015 2025-05-05 19:49:06.847002 :93269B23:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 19:49:06.847002 :93269B24:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 19:49:06.847002 :93269B25:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 19:49:06.847003 :93269B26:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 19:49:06.847003 :93269B27:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 19:49:06.847501 :93269B28: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-05 19:49:06.847544 :93269B2F: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-05 19:59:07.847864 :93276340:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 19:59:08.211977 :93276378:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 19:59:08.207 2025-05-05 19:59:08.211979 :93276379:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11988 pso_num=22 pso_serial#=246 2025-05-05 19:59:08.212111 :9327637A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11988 osp_idx=0 osp_ver=160131 osp_pg=0 (spawn #151836) 2025-05-05 19:59:08.212112 :9327637B: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-05 19:59:08.212112 :9327637C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=3745925375 2025-05-05 19:59:08.212112 :9327637D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 19:59:08.212113 :9327637E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 19:59:08.212113 :9327637F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 19:59:08.212113 :93276380:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 19:59:08.212114 :93276381:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 19:59:08.212617 :93276382: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-05 19:59:08.212666 :9327638A: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-05 20:09:09.118953 :9328375F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 20:09:09.529822 :93283796:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 20:09:09.525 2025-05-05 20:09:09.529824 :93283797:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9008 pso_num=22 pso_serial#=247 2025-05-05 20:09:09.529955 :93283798:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9008 osp_idx=0 osp_ver=160132 osp_pg=0 (spawn #151862) 2025-05-05 20:09:09.529956 :93283799: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-05 20:09:09.529957 :9328379A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=3746526703 2025-05-05 20:09:09.529957 :9328379B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 20:09:09.529958 :9328379C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 20:09:09.529958 :9328379D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 20:09:09.529959 :9328379E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 20:09:09.529959 :9328379F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 20:09:09.530433 :932837A0: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-05 20:09:09.530487 :932837AA: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-05 20:19:10.409794 :9328FF87:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 20:19:10.883250 :9328FFC1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 20:19:10.878 2025-05-05 20:19:10.883252 :9328FFC2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9460 pso_num=22 pso_serial#=248 2025-05-05 20:19:10.883387 :9328FFC3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9460 osp_idx=0 osp_ver=160133 osp_pg=0 (spawn #151884) 2025-05-05 20:19:10.883387 :9328FFC4: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-05 20:19:10.883388 :9328FFC5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=3747128046 2025-05-05 20:19:10.883388 :9328FFC6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 20:19:10.883388 :9328FFC7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 20:19:10.883388 :9328FFC8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 20:19:10.883388 :9328FFC9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 20:19:10.883389 :9328FFCA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 20:19:10.883882 :9328FFCB: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-05 20:19:10.883926 :9328FFD3: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-05 20:29:11.754892 :9329C86D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 20:29:12.259553 :9329C8B1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 20:29:12.254 2025-05-05 20:29:12.259555 :9329C8B2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10436 pso_num=22 pso_serial#=249 2025-05-05 20:29:12.259694 :9329C8B3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10436 osp_idx=0 osp_ver=160134 osp_pg=0 (spawn #151909) 2025-05-05 20:29:12.259695 :9329C8B4: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-05 20:29:12.259695 :9329C8B5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=13 time=3747729421 2025-05-05 20:29:12.259696 :9329C8B6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 20:29:12.259696 :9329C8B7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 20:29:12.259697 :9329C8B8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 20:29:12.259697 :9329C8B9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 20:29:12.259697 :9329C8BA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 20:29:12.260190 :9329C8BB: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-05 20:29:12.260235 :9329C8C2: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-05 20:39:13.087692 :932A9216:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 20:39:13.784987 :932A925C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 20:39:13.780 2025-05-05 20:39:13.784989 :932A925D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11500 pso_num=22 pso_serial#=250 2025-05-05 20:39:13.785120 :932A925E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11500 osp_idx=0 osp_ver=160135 osp_pg=0 (spawn #151932) 2025-05-05 20:39:13.785121 :932A925F: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-05 20:39:13.785121 :932A9260:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=14 time=3748330953 2025-05-05 20:39:13.785122 :932A9261:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 20:39:13.785122 :932A9262:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 20:39:13.785122 :932A9263:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 20:39:13.785123 :932A9264:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 20:39:13.785123 :932A9265:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 20:39:13.785660 :932A9266: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-05 20:39:13.785706 :932A926F: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-05 20:49:14.646726 :932B5B3A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 20:49:15.167043 :932B5B72:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 20:49:15.162 2025-05-05 20:49:15.167044 :932B5B73:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12204 pso_num=22 pso_serial#=251 2025-05-05 20:49:15.167181 :932B5B74:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12204 osp_idx=0 osp_ver=160136 osp_pg=0 (spawn #151954) 2025-05-05 20:49:15.167181 :932B5B75: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-05 20:49:15.167182 :932B5B76:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=15 time=3748932328 2025-05-05 20:49:15.167182 :932B5B77:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 20:49:15.167182 :932B5B78:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 20:49:15.167183 :932B5B79:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 20:49:15.167183 :932B5B7A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 20:49:15.167183 :932B5B7B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 20:49:15.167672 :932B5B7C: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-05 20:49:15.167716 :932B5B84: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-05 20:59:16.044972 :932C2309:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 20:59:16.596570 :932C234D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 20:59:16.591 2025-05-05 20:59:16.596572 :932C234E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7276 pso_num=22 pso_serial#=252 2025-05-05 20:59:16.596749 :932C234F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7276 osp_idx=0 osp_ver=160137 osp_pg=0 (spawn #151977) 2025-05-05 20:59:16.596749 :932C2350: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-05 20:59:16.596750 :932C2351:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=16 time=3749533765 2025-05-05 20:59:16.596750 :932C2352:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 20:59:16.596750 :932C2353:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 20:59:16.596751 :932C2354:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 20:59:16.596751 :932C2355:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 20:59:16.596752 :932C2356:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 20:59:16.597273 :932C2357: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-05 20:59:16.597316 :932C235F: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-05 21:09:17.435810 :932CF7A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 21:09:18.044124 :932CF7F1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 21:09:18.039 2025-05-05 21:09:18.044126 :932CF7F2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9772 pso_num=22 pso_serial#=253 2025-05-05 21:09:18.044277 :932CF7F3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9772 osp_idx=0 osp_ver=160138 osp_pg=0 (spawn #152003) 2025-05-05 21:09:18.044277 :932CF7F4: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-05 21:09:18.044278 :932CF7F5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=17 time=3750135203 2025-05-05 21:09:18.044278 :932CF7F6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 21:09:18.044279 :932CF7F7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 21:09:18.044279 :932CF7F8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 21:09:18.044279 :932CF7F9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 21:09:18.044280 :932CF7FA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 21:09:18.044804 :932CF7FB: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-05 21:09:18.044848 :932CF803: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-05 21:19:18.908355 :932DBF6C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 21:19:19.329387 :932DBFA1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 21:19:19.324 2025-05-05 21:19:19.329389 :932DBFA2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11496 pso_num=22 pso_serial#=254 2025-05-05 21:19:19.329524 :932DBFA3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11496 osp_idx=0 osp_ver=160139 osp_pg=0 (spawn #152025) 2025-05-05 21:19:19.329525 :932DBFA4: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-05 21:19:19.329525 :932DBFA5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=18 time=3750736500 2025-05-05 21:19:19.329526 :932DBFA6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 21:19:19.329526 :932DBFA7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 21:19:19.329527 :932DBFA8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 21:19:19.329527 :932DBFA9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 21:19:19.329527 :932DBFAA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 21:19:19.330010 :932DBFAB: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-05 21:19:19.330055 :932DBFB3: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-05 21:29:20.178664 :932E8899:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 21:29:20.792801 :932E8903:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 21:29:20.788 2025-05-05 21:29:20.792803 :932E8904:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6552 pso_num=22 pso_serial#=255 2025-05-05 21:29:20.792940 :932E8905:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6552 osp_idx=0 osp_ver=160140 osp_pg=0 (spawn #152050) 2025-05-05 21:29:20.792940 :932E8906: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-05 21:29:20.792941 :932E8907:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=19 time=3751337953 2025-05-05 21:29:20.792941 :932E8908:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 21:29:20.792942 :932E8909:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 21:29:20.792942 :932E890A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 21:29:20.792942 :932E890B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 21:29:20.792943 :932E890C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 21:29:20.793444 :932E890D: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-05 21:29:20.793491 :932E8915: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-05 21:29:35.816264 :932E8E30: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-05-05 21:29:35.816349 :932E8E33: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-05-05 21:29:35.816350 :932E8E35:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=86, exc=86, tot=86 2025-05-05 21:39:21.601542 :932F511E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 21:39:22.069266 :932F5157:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 21:39:22.064 2025-05-05 21:39:22.069268 :932F5158:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8588 pso_num=22 pso_serial#=1 2025-05-05 21:39:22.069401 :932F5159:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8588 osp_idx=0 osp_ver=160141 osp_pg=0 (spawn #152073) 2025-05-05 21:39:22.069402 :932F515A: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-05 21:39:22.069403 :932F515B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=20 time=3751939234 2025-05-05 21:39:22.069403 :932F515C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 21:39:22.069403 :932F515D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 21:39:22.069404 :932F515E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 21:39:22.069404 :932F515F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 21:39:22.069404 :932F5160:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 21:39:22.069954 :932F5161: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-05 21:39:22.069998 :932F516A: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-05 21:40:32.016638 :932F68FC: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-05 21:49:23.420993 :93301A1F: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-05 21:59:24.322684 :9330E1A0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-05 21:59:24.717940 :9330E1FF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-05 21:59:24.713 2025-05-05 21:59:24.717943 :9330E200:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10932 pso_num=22 pso_serial#=2 2025-05-05 21:59:24.718085 :9330E201:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10932 osp_idx=0 osp_ver=160142 osp_pg=0 (spawn #152117) 2025-05-05 21:59:24.718085 :9330E202: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-05 21:59:24.718086 :9330E203:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=21 time=3753141890 2025-05-05 21:59:24.718086 :9330E204:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 21:59:24.718087 :9330E205:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 21:59:24.718087 :9330E206:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 21:59:24.718087 :9330E207:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 21:59:24.718088 :9330E208:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 21:59:24.718581 :9330E209: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-05 21:59:24.718633 :9330E211: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: 10932, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de