Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250615180859\orcl_w000_17048_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250615180859\orcl_w000_17048_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:36638M/63366M, Ph+PgF:43127M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17048, image: ORACLE.EXE (W000) *** 2025-06-15 18:08:59.587 *** SESSION ID:(201.25059) 2025-06-15 18:08:59.587 *** 2025-06-15 18:08:59.587 Process diagnostic dump for ORACLE.EXE (W000), OS id=17048, pid: 25, proc_ser: 60, sid: 201, sess_ser: 25059 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=2.466740 sec, exc=2.466740 sec, total=2.466740 sec wait times: max=5.000000 sec, heur=2 min 42 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=32 seq_num=33 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.000041 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.007223 sec, exc=5.007223 sec, total=5.007223 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.001721 sec, exc=5.001721 sec, total=5.001721 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.004639 sec, exc=5.004639 sec, total=5.004639 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=28 seq_num=29 snap_id=1 wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.014775 sec, exc=5.014775 sec, total=5.014775 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.014754 sec, exc=5.014754 sec, total=5.014754 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.005135 sec, exc=5.005135 sec, total=5.005135 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.000972 sec, exc=5.000972 sec, total=5.000972 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.015332 sec, exc=5.015332 sec, total=5.015332 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 201 serial 25059 --------------------------------------------------- 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, 18:06:59 - 18:08:59] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-15 18:08:59.587 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17048, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-15 15:45:58.969324 :A51229F6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 15:45:59.489416 :A5122A60:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 15:45:59.484 2025-06-15 15:45:59.489418 :A5122A61:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12668 pso_num=25 pso_serial#=47 2025-06-15 15:45:59.489562 :A5122A62:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12668 osp_idx=23 osp_ver=158514 osp_pg=0 (spawn #288288) 2025-06-15 15:45:59.489563 :A5122A63:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 15:45:59.489563 :A5122A64:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=142 time=2978102672 2025-06-15 15:45:59.489563 :A5122A65:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 15:45:59.489564 :A5122A66:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 15:45:59.489564 :A5122A67:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 15:45:59.489564 :A5122A68:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 15:45:59.489565 :A5122A69:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 15:45:59.490079 :A5122A6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 15:45:59.490125 :A5122A74:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 15:56:00.325821 :A512F403:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 15:56:00.768911 :A512F448:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 15:56:00.763 2025-06-15 15:56:00.768913 :A512F449:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17016 pso_num=25 pso_serial#=48 2025-06-15 15:56:00.769084 :A512F44A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17016 osp_idx=23 osp_ver=158515 osp_pg=0 (spawn #288311) 2025-06-15 15:56:00.769085 :A512F44B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 15:56:00.769085 :A512F44C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=143 time=2978703954 2025-06-15 15:56:00.769086 :A512F44D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 15:56:00.769086 :A512F44E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 15:56:00.769086 :A512F44F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 15:56:00.769087 :A512F450:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 15:56:00.769087 :A512F451:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 15:56:00.769697 :A512F452:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 15:56:00.769780 :A512F45C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 16:06:01.569502 :A513C7A5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 16:06:02.058752 :A513C7DE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 16:06:02.054 2025-06-15 16:06:02.058754 :A513C7DF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17096 pso_num=25 pso_serial#=49 2025-06-15 16:06:02.058943 :A513C7E0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17096 osp_idx=23 osp_ver=158516 osp_pg=0 (spawn #288338) 2025-06-15 16:06:02.058944 :A513C7E1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 16:06:02.058944 :A513C7E2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=144 time=2979305250 2025-06-15 16:06:02.058945 :A513C7E3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 16:06:02.058945 :A513C7E4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 16:06:02.058946 :A513C7E5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 16:06:02.058946 :A513C7E6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 16:06:02.058947 :A513C7E7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 16:06:02.059454 :A513C7E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 16:06:02.059501 :A513C7EF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 16:16:02.861344 :A5149460:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 16:16:03.350420 :A51494A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 16:16:03.345 2025-06-15 16:16:03.350422 :A51494A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11420 pso_num=25 pso_serial#=50 2025-06-15 16:16:03.350562 :A51494A6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11420 osp_idx=23 osp_ver=158517 osp_pg=0 (spawn #288360) 2025-06-15 16:16:03.350563 :A51494A7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 16:16:03.350563 :A51494A8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=145 time=2979906547 2025-06-15 16:16:03.350563 :A51494A9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 16:16:03.350564 :A51494AA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 16:16:03.350564 :A51494AB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 16:16:03.350564 :A51494AC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 16:16:03.350565 :A51494AD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 16:16:03.351052 :A51494AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 16:16:03.351097 :A51494B5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 16:26:04.197823 :A5155D9A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 16:26:04.655649 :A5155DDF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 16:26:04.651 2025-06-15 16:26:04.655651 :A5155DE0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16092 pso_num=25 pso_serial#=51 2025-06-15 16:26:04.655786 :A5155DE1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16092 osp_idx=23 osp_ver=158518 osp_pg=0 (spawn #288383) 2025-06-15 16:26:04.655787 :A5155DE2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 16:26:04.655787 :A5155DE3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=146 time=2980507844 2025-06-15 16:26:04.655788 :A5155DE4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 16:26:04.655788 :A5155DE5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 16:26:04.655788 :A5155DE6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 16:26:04.655789 :A5155DE7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 16:26:04.655789 :A5155DE8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 16:26:04.656275 :A5155DE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 16:26:04.656319 :A5155DF1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 16:36:05.438029 :A5162843:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 16:36:05.973889 :A516287F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 16:36:05.969 2025-06-15 16:36:05.973892 :A5162880:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13000 pso_num=25 pso_serial#=52 2025-06-15 16:36:05.974026 :A5162881:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13000 osp_idx=23 osp_ver=158519 osp_pg=0 (spawn #288406) 2025-06-15 16:36:05.974027 :A5162882:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 16:36:05.974027 :A5162883:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=2981109157 2025-06-15 16:36:05.974028 :A5162884:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 16:36:05.974028 :A5162885:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 16:36:05.974029 :A5162886:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 16:36:05.974029 :A5162887:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 16:36:05.974029 :A5162888:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 16:36:05.974513 :A5162889:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 16:36:05.974556 :A5162891:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 16:46:06.765310 :A516F22C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 16:46:07.160581 :A516F26D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 16:46:07.156 2025-06-15 16:46:07.160584 :A516F26E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17204 pso_num=25 pso_serial#=53 2025-06-15 16:46:07.160774 :A516F26F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17204 osp_idx=23 osp_ver=158520 osp_pg=0 (spawn #288428) 2025-06-15 16:46:07.160775 :A516F270:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 16:46:07.160775 :A516F271:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=2981710344 2025-06-15 16:46:07.160776 :A516F272:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 16:46:07.160776 :A516F273:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 16:46:07.160777 :A516F274:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 16:46:07.160777 :A516F275:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 16:46:07.160777 :A516F276:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 16:46:07.161287 :A516F277:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 16:46:07.161332 :A516F280:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 16:56:08.082980 :A517BC6B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 16:56:08.322096 :A517BCA0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 16:56:08.317 2025-06-15 16:56:08.322098 :A517BCA1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13448 pso_num=25 pso_serial#=54 2025-06-15 16:56:08.322233 :A517BCA2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13448 osp_idx=23 osp_ver=158521 osp_pg=0 (spawn #288451) 2025-06-15 16:56:08.322234 :A517BCA3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 16:56:08.322234 :A517BCA4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=2982311516 2025-06-15 16:56:08.322234 :A517BCA5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 16:56:08.322235 :A517BCA6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 16:56:08.322235 :A517BCA7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 16:56:08.322236 :A517BCA8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 16:56:08.322236 :A517BCA9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 16:56:08.322781 :A517BCAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 16:56:08.322826 :A517BCB3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 17:06:09.228472 :A5189157:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 17:06:09.592600 :A518919E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 17:06:09.587 2025-06-15 17:06:09.592602 :A518919F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16560 pso_num=25 pso_serial#=55 2025-06-15 17:06:09.592736 :A51891A0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16560 osp_idx=23 osp_ver=158522 osp_pg=0 (spawn #288477) 2025-06-15 17:06:09.592737 :A51891A1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 17:06:09.592737 :A51891A2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=2982912782 2025-06-15 17:06:09.592738 :A51891A3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 17:06:09.592738 :A51891A4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 17:06:09.592739 :A51891A5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 17:06:09.592739 :A51891A6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 17:06:09.592739 :A51891A7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 17:06:09.593237 :A51891A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 17:06:09.593285 :A51891AF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 17:16:10.438179 :A5195BE1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 17:16:10.791222 :A5195C18:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 17:16:10.786 2025-06-15 17:16:10.791224 :A5195C19:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15968 pso_num=25 pso_serial#=56 2025-06-15 17:16:10.791365 :A5195C1A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15968 osp_idx=23 osp_ver=158523 osp_pg=0 (spawn #288499) 2025-06-15 17:16:10.791366 :A5195C1B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 17:16:10.791368 :A5195C1C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=2983513985 2025-06-15 17:16:10.791368 :A5195C1D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 17:16:10.791369 :A5195C1E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 17:16:10.791369 :A5195C1F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 17:16:10.791369 :A5195C20:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 17:16:10.791370 :A5195C21:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 17:16:10.791873 :A5195C22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 17:16:10.791919 :A5195C2A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 17:26:11.676732 :A51A26C9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 17:26:12.025237 :A51A2714:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 17:26:12.021 2025-06-15 17:26:12.025239 :A51A2715:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16752 pso_num=25 pso_serial#=57 2025-06-15 17:26:12.025372 :A51A2716:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16752 osp_idx=23 osp_ver=158524 osp_pg=0 (spawn #288522) 2025-06-15 17:26:12.025373 :A51A2717:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 17:26:12.025373 :A51A2718:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=2984115219 2025-06-15 17:26:12.025373 :A51A2719:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 17:26:12.025374 :A51A271A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 17:26:12.025374 :A51A271B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 17:26:12.025374 :A51A271C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 17:26:12.025375 :A51A271D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 17:26:12.025866 :A51A271E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 17:26:12.025912 :A51A2726:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 17:26:27.034321 :A51A2C5E:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-06-15 17:26:27.034337 :A51A2C68:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-06-15 17:26:27.034338 :A51A2C69:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=16, exc=16, tot=16 2025-06-15 17:36:12.884721 :A51AF1C9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 17:36:13.170568 :A51AF204:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 17:36:13.166 2025-06-15 17:36:13.170571 :A51AF205:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12732 pso_num=25 pso_serial#=58 2025-06-15 17:36:13.170709 :A51AF206:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12732 osp_idx=23 osp_ver=158525 osp_pg=0 (spawn #288545) 2025-06-15 17:36:13.170710 :A51AF207:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 17:36:13.170710 :A51AF208:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=2984716360 2025-06-15 17:36:13.170711 :A51AF209:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 17:36:13.170711 :A51AF20A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 17:36:13.170711 :A51AF20B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 17:36:13.170712 :A51AF20C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 17:36:13.170712 :A51AF20D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 17:36:13.171193 :A51AF20E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 17:36:13.171239 :A51AF215:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 17:36:20.878166 :A51AF4D1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-15 17:46:14.322040 :A51BBABF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-15 17:56:15.268031 :A51C835C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 17:56:15.616294 :A51C83A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 17:56:15.612 2025-06-15 17:56:15.616296 :A51C83A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15620 pso_num=25 pso_serial#=59 2025-06-15 17:56:15.616436 :A51C83A6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15620 osp_idx=23 osp_ver=158526 osp_pg=0 (spawn #288589) 2025-06-15 17:56:15.616436 :A51C83A7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 17:56:15.616437 :A51C83A8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=2985918813 2025-06-15 17:56:15.616437 :A51C83A9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 17:56:15.616437 :A51C83AA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 17:56:15.616437 :A51C83AB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 17:56:15.616438 :A51C83AC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 17:56:15.616438 :A51C83AD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 17:56:15.616923 :A51C83AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 17:56:15.616966 :A51C83B6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-15 18:06:16.435694 :A51D558F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 18:06:16.835528 :A51D55D4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 18:06:16.830 2025-06-15 18:06:16.835530 :A51D55D5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17048 pso_num=25 pso_serial#=60 2025-06-15 18:06:16.835662 :A51D55D6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17048 osp_idx=23 osp_ver=158527 osp_pg=0 (spawn #288615) 2025-06-15 18:06:16.835663 :A51D55D7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 18:06:16.835663 :A51D55D8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=2986520032 2025-06-15 18:06:16.835664 :A51D55D9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 18:06:16.835664 :A51D55DA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 18:06:16.835665 :A51D55DB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 18:06:16.835665 :A51D55DC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 18:06:16.835665 :A51D55DD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 18:06:16.836155 :A51D55DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-15 18:06:16.836198 :A51D55E6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 17048, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de