Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250604082017\orcl_m002_13744_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250604082017\orcl_m002_13744_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:36940M/63366M, Ph+PgF:43675M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 13744, image: ORACLE.EXE (M002) *** 2025-06-04 08:20:18.067 *** SESSION ID:(193.36481) 2025-06-04 08:20:18.067 *** 2025-06-04 08:20:18.067 Process diagnostic dump for ORACLE.EXE (M002), OS id=13744, pid: 24, proc_ser: 58, sid: 193, sess_ser: 36481 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=589 seq_num=590 snap_id=1 wait times: snap=0.003199 sec, exc=0.003199 sec, total=0.003199 sec wait times: max=infinite, heur=0.004197 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000006 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=588 seq_num=589 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=587 seq_num=588 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=586 seq_num=587 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=585 seq_num=586 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=584 seq_num=585 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=583 seq_num=584 snap_id=1 wait times: snap=0.000055 sec, exc=0.000055 sec, total=0.000055 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=582 seq_num=583 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=581 seq_num=582 snap_id=1 wait times: snap=0.000054 sec, exc=0.000054 sec, total=0.000054 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=580 seq_num=581 snap_id=1 wait times: snap=0.000058 sec, exc=0.000058 sec, total=0.000058 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=579 seq_num=580 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 193 serial 36481 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 08:20:17] waited for 'ADR block file read', seq_num: 511 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:20:16] idle wait at each sample [session created at: 08:20:17] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:20:17 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-04 08:20:18.067 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 13744, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-04 08:06:55.389903 :A01C5FAE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:06:55.389904 :A01C5FAF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:06:55.390394 :A01C5FB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:07:20.436908 :A01C683A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-04 08:07:50.494276 :A01C7203:db_trace:ksl2.c@2324:kslwtbctx(): [10005:24:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-06-04 08:07:50.494305 :A01C7204:db_trace:ksl2.c@2611:kslwtectx(): [10005:24:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-06-04 08:07:50.494307 :A01C7205:db_trace:ksl2.c@2619:kslwtectx(): [10005:24:0] KSL WAIT END wait times (usecs) - snap=26, exc=26, tot=26 2025-06-04 08:07:50.494437 :A01C7206:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:08:20.506325 :A01C7BBF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:08:20.495 2025-06-04 08:08:20.506326 :A01C7BC0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4804 pso_num=24 pso_serial#=43 2025-06-04 08:08:20.506457 :A01C7BC1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4804 osp_idx=22 osp_ver=360122 osp_pg=0 (spawn #250566) 2025-06-04 08:08:20.506457 :A01C7BC2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:08:20.506458 :A01C7BC3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=174 time=2000259141 2025-06-04 08:08:20.506458 :A01C7BC4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:08:20.506459 :A01C7BC5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:08:20.506459 :A01C7BC6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:08:20.506460 :A01C7BC7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:08:20.506460 :A01C7BC8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:08:20.506932 :A01C7BC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:08:50.563642 :A01C8583:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:09:20.578084 :A01C8F09:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:09:20.566 2025-06-04 08:09:20.578087 :A01C8F0A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14720 pso_num=24 pso_serial#=44 2025-06-04 08:09:20.578202 :A01C8F0B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14720 osp_idx=22 osp_ver=360123 osp_pg=0 (spawn #250568) 2025-06-04 08:09:20.578203 :A01C8F0C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:09:20.578204 :A01C8F0D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=175 time=2000319219 2025-06-04 08:09:20.578204 :A01C8F0E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:09:20.578204 :A01C8F0F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:09:20.578205 :A01C8F10:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:09:20.578205 :A01C8F11:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:09:20.578205 :A01C8F12:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:09:20.578681 :A01C8F13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:09:50.671383 :A01C98D2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:10:20.686114 :A01CA57B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:10:20.675 2025-06-04 08:10:20.686116 :A01CA57C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16260 pso_num=24 pso_serial#=45 2025-06-04 08:10:20.686256 :A01CA57D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16260 osp_idx=22 osp_ver=360124 osp_pg=0 (spawn #250570) 2025-06-04 08:10:20.686256 :A01CA57E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:10:20.686257 :A01CA57F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=176 time=2000379329 2025-06-04 08:10:20.686257 :A01CA580:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:10:20.686257 :A01CA581:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:10:20.686258 :A01CA582:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:10:20.686258 :A01CA583:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:10:20.686259 :A01CA584:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:10:20.686779 :A01CA585:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:10:50.765296 :A01CB032:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:11:20.788727 :A01CBA30:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:11:20.778 2025-06-04 08:11:20.788729 :A01CBA31:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14008 pso_num=24 pso_serial#=46 2025-06-04 08:11:20.788859 :A01CBA32:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14008 osp_idx=22 osp_ver=360125 osp_pg=0 (spawn #250572) 2025-06-04 08:11:20.788860 :A01CBA33:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:11:20.788860 :A01CBA34:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=177 time=2000439422 2025-06-04 08:11:20.788861 :A01CBA35:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:11:20.788861 :A01CBA36:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:11:20.788862 :A01CBA37:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:11:20.788862 :A01CBA38:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:11:20.788862 :A01CBA39:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:11:20.789336 :A01CBA3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:11:50.858681 :A01CC407:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:12:20.910073 :A01CCDC4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:12:20.899 2025-06-04 08:12:20.910075 :A01CCDC5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12296 pso_num=24 pso_serial#=47 2025-06-04 08:12:20.910212 :A01CCDC6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12296 osp_idx=22 osp_ver=360126 osp_pg=0 (spawn #250574) 2025-06-04 08:12:20.910212 :A01CCDC7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:12:20.910213 :A01CCDC8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=178 time=2000499547 2025-06-04 08:12:20.910213 :A01CCDC9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:12:20.910214 :A01CCDCA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:12:20.910214 :A01CCDCB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:12:20.910214 :A01CCDCC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:12:20.910215 :A01CCDCD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:12:20.910704 :A01CCDCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:12:50.973056 :A01CD797:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:13:21.001213 :A01CE176:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:13:20.990 2025-06-04 08:13:21.001215 :A01CE177:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14764 pso_num=24 pso_serial#=48 2025-06-04 08:13:21.001342 :A01CE178:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14764 osp_idx=22 osp_ver=360127 osp_pg=0 (spawn #250576) 2025-06-04 08:13:21.001342 :A01CE179:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:13:21.001343 :A01CE17A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=179 time=2000559641 2025-06-04 08:13:21.001343 :A01CE17B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:13:21.001343 :A01CE17C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:13:21.001344 :A01CE17D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:13:21.001344 :A01CE17E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:13:21.001344 :A01CE17F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:13:21.001842 :A01CE180:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:13:51.062510 :A01CEBB4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:14:21.084588 :A01CF57D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:14:21.074 2025-06-04 08:14:21.084590 :A01CF57E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15116 pso_num=24 pso_serial#=49 2025-06-04 08:14:21.084727 :A01CF57F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15116 osp_idx=22 osp_ver=360128 osp_pg=0 (spawn #250579) 2025-06-04 08:14:21.084728 :A01CF580:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:14:21.084729 :A01CF581:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=180 time=2000619719 2025-06-04 08:14:21.084729 :A01CF582:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:14:21.084729 :A01CF583:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:14:21.084730 :A01CF584:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:14:21.084730 :A01CF585:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:14:21.084731 :A01CF586:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:14:21.085219 :A01CF587:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:14:51.178424 :A01CFF07:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:15:21.199154 :A01D0935:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:15:21.187 2025-06-04 08:15:21.199156 :A01D0936:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15108 pso_num=24 pso_serial#=50 2025-06-04 08:15:21.199275 :A01D0937:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15108 osp_idx=22 osp_ver=360129 osp_pg=0 (spawn #250581) 2025-06-04 08:15:21.199276 :A01D0938:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:15:21.199277 :A01D0939:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=181 time=2000679844 2025-06-04 08:15:21.199277 :A01D093A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:15:21.199277 :A01D093B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:15:21.199278 :A01D093C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:15:21.199278 :A01D093D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:15:21.199278 :A01D093E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:15:21.199759 :A01D093F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:15:51.261611 :A01D13FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:16:21.303459 :A01D1DF2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:16:21.292 2025-06-04 08:16:21.303461 :A01D1DF3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13728 pso_num=24 pso_serial#=51 2025-06-04 08:16:21.303585 :A01D1DF4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13728 osp_idx=22 osp_ver=360130 osp_pg=0 (spawn #250585) 2025-06-04 08:16:21.303586 :A01D1DF5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:16:21.303588 :A01D1DF6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=182 time=2000739938 2025-06-04 08:16:21.303588 :A01D1DF7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:16:21.303588 :A01D1DF8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:16:21.303589 :A01D1DF9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:16:21.303589 :A01D1DFA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:16:21.303589 :A01D1DFB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:16:21.304107 :A01D1DFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:16:51.371075 :A01D27A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:16:56.349953 :A01D2951:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:16:56.338 2025-06-04 08:16:56.349955 :A01D2952:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13096 pso_num=24 pso_serial#=52 2025-06-04 08:16:56.350136 :A01D2953:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13096 osp_idx=22 osp_ver=360131 osp_pg=0 (spawn #250587) 2025-06-04 08:16:56.350136 :A01D2954:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:16:56.350137 :A01D2955:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=183 time=2000774985 2025-06-04 08:16:56.350138 :A01D2956:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:16:56.350138 :A01D2957:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:16:56.350138 :A01D2958:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:16:56.350139 :A01D2959:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:16:56.350139 :A01D295A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:16:56.350641 :A01D295B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:17:21.383674 :A01D3175:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-04 08:17:51.485951 :A01D3B39:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:18:21.510677 :A01D4510:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:18:21.499 2025-06-04 08:18:21.510679 :A01D4511:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11536 pso_num=24 pso_serial#=53 2025-06-04 08:18:21.510798 :A01D4512:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11536 osp_idx=22 osp_ver=360132 osp_pg=0 (spawn #250589) 2025-06-04 08:18:21.510798 :A01D4513:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:18:21.510798 :A01D4514:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=184 time=2000860157 2025-06-04 08:18:21.510799 :A01D4515:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:18:21.510799 :A01D4516:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:18:21.510799 :A01D4517:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:18:21.510799 :A01D4518:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:18:21.510800 :A01D4519:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:18:21.511287 :A01D451A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:18:51.580729 :A01D4EF7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:19:21.611001 :A01D58D2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:19:21.600 2025-06-04 08:19:21.611004 :A01D58D3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3836 pso_num=24 pso_serial#=54 2025-06-04 08:19:21.611122 :A01D58D4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3836 osp_idx=22 osp_ver=360133 osp_pg=0 (spawn #250591) 2025-06-04 08:19:21.611122 :A01D58D5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:19:21.611122 :A01D58D6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=185 time=2000920250 2025-06-04 08:19:21.611122 :A01D58D7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:19:21.611123 :A01D58D8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:19:21.611123 :A01D58D9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:19:21.611123 :A01D58DA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:19:21.611124 :A01D58DB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:19:21.611636 :A01D58DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:19:51.665369 :A01D628E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:20:15.068343 :A01DA345:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:20:15.067 2025-06-04 08:20:15.068345 :A01DA346:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15364 pso_num=24 pso_serial#=55 2025-06-04 08:20:15.068515 :A01DA347:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15364 osp_idx=22 osp_ver=360134 osp_pg=0 (spawn #250593) 2025-06-04 08:20:15.068515 :A01DA348:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:20:15.068516 :A01DA349:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=186 time=2000973704 2025-06-04 08:20:15.068517 :A01DA34A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:20:15.068517 :A01DA34B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:20:15.068517 :A01DA34C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:20:15.068517 :A01DA34D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:20:15.068518 :A01DA34E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:20:15.069807 :A01DA34F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:20:15.069857 :A01DA355:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-04 08:20:15.175202 :A01DA387:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:20:16.736474 :A01DA42D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:20:16.723 2025-06-04 08:20:16.736477 :A01DA42E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15396 pso_num=24 pso_serial#=56 2025-06-04 08:20:16.736614 :A01DA42F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15396 (legacy spawn) 2025-06-04 08:20:16.743173 :A01DA430:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-04 08:20:16.743328 :A01DA435:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-04 08:20:16.744115 :A01DA43A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:20:16.752745 :A01DA43D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:20:16.739 2025-06-04 08:20:16.752747 :A01DA43E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15420 pso_num=24 pso_serial#=57 2025-06-04 08:20:16.752880 :A01DA43F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15420 (legacy spawn) 2025-06-04 08:20:16.759327 :A01DA440:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-04 08:20:16.759485 :A01DA448:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-04 08:20:16.760237 :A01DA44A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-04 08:20:17.250899 :A01DA46C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-04 08:20:17.239 2025-06-04 08:20:17.250901 :A01DA46D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13744 pso_num=24 pso_serial#=58 2025-06-04 08:20:17.251033 :A01DA46E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13744 osp_idx=22 osp_ver=360137 osp_pg=0 (spawn #250595) 2025-06-04 08:20:17.251034 :A01DA46F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:20:17.251034 :A01DA470:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=187 time=2000975891 2025-06-04 08:20:17.251035 :A01DA471:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:20:17.251035 :A01DA472:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:20:17.251035 :A01DA473:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:20:17.251036 :A01DA474:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:20:17.251036 :A01DA475:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:20:17.251563 :A01DA476:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-04 08:20:17.251622 :A01DA47C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 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 24 (osid: 13744, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de