Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250529081528\orcl_m002_14080_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250529081528\orcl_m002_14080_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:37273M/63366M, Ph+PgF:44201M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 14080, image: ORACLE.EXE (M002) *** 2025-05-29 08:15:28.252 *** SESSION ID:(193.52529) 2025-05-29 08:15:28.252 *** 2025-05-29 08:15:28.252 Process diagnostic dump for ORACLE.EXE (M002), OS id=14080, pid: 24, proc_ser: 28, sid: 193, sess_ser: 52529 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=416 seq_num=417 snap_id=1 wait times: snap=0.002780 sec, exc=0.002780 sec, total=0.002780 sec wait times: max=infinite, heur=0.294578 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.000007 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=415 seq_num=416 snap_id=1 wait times: snap=0.000341 sec, exc=0.000341 sec, total=0.000341 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=414 seq_num=415 snap_id=1 wait times: snap=0.000459 sec, exc=0.000459 sec, total=0.000459 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=413 seq_num=414 snap_id=1 wait times: snap=0.002627 sec, exc=0.002627 sec, total=0.002627 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=412 seq_num=413 snap_id=1 wait times: snap=0.005592 sec, exc=0.005592 sec, total=0.005592 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=411 seq_num=412 snap_id=1 wait times: snap=0.002638 sec, exc=0.002638 sec, total=0.002638 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=410 seq_num=411 snap_id=1 wait times: snap=0.002354 sec, exc=0.002354 sec, total=0.002354 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=409 seq_num=410 snap_id=1 wait times: snap=0.001878 sec, exc=0.001878 sec, total=0.001878 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=408 seq_num=409 snap_id=1 wait times: snap=0.005810 sec, exc=0.005810 sec, total=0.005810 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000014 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=407 seq_num=408 snap_id=1 wait times: snap=0.004908 sec, exc=0.004908 sec, total=0.004908 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000013 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=406 seq_num=407 snap_id=1 wait times: snap=0.001698 sec, exc=0.001698 sec, total=0.001698 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time Sampled Session History of session 193 serial 52529 --------------------------------------------------- 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:15:28] waited for 'ADR block file read', seq_num: 401 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:15:27] idle wait at each sample [session created at: 08:15:27] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:15:28 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-29 08:15:28.252 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 14080, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-29 08:03:08.494125 :9D790A30:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:03:13.431053 :9D790BD8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:03:13.420 2025-05-29 08:03:13.431056 :9D790BD9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14736 pso_num=24 pso_serial#=14 2025-05-29 08:03:13.431187 :9D790BDA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14736 osp_idx=22 osp_ver=338928 osp_pg=0 (spawn #230539) 2025-05-29 08:03:13.431187 :9D790BDB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:03:13.431188 :9D790BDC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=151 time=1481565672 2025-05-29 08:03:13.431188 :9D790BDD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:03:13.431189 :9D790BDE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:03:13.431189 :9D790BDF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:03:13.431189 :9D790BE0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:03:13.431190 :9D790BE1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:03:13.431652 :9D790BE2: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-05-29 08:03:38.485513 :9D791421: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-05-29 08:04:08.549653 :9D791DB6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:04:38.576864 :9D792758:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:04:38.565 2025-05-29 08:04:38.576867 :9D792759:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14932 pso_num=24 pso_serial#=15 2025-05-29 08:04:38.576997 :9D79275A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14932 osp_idx=22 osp_ver=338929 osp_pg=0 (spawn #230541) 2025-05-29 08:04:38.576998 :9D79275B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:04:38.576998 :9D79275C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=152 time=1481650829 2025-05-29 08:04:38.576999 :9D79275D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:04:38.576999 :9D79275E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:04:38.577000 :9D79275F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:04:38.577000 :9D792760:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:04:38.577000 :9D792761:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:04:38.577470 :9D792762: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-05-29 08:05:08.664151 :9D79312A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:05:38.654487 :9D79403E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:05:38.648 2025-05-29 08:05:38.654489 :9D79403F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14656 pso_num=24 pso_serial#=16 2025-05-29 08:05:38.654611 :9D794040:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14656 osp_idx=22 osp_ver=338930 osp_pg=0 (spawn #230543) 2025-05-29 08:05:38.654611 :9D794041:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:05:38.654612 :9D794042:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=153 time=1481710907 2025-05-29 08:05:38.654612 :9D794043:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:05:38.654612 :9D794044:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:05:38.654613 :9D794045:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:05:38.654613 :9D794046:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:05:38.654614 :9D794047:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:05:38.655137 :9D794048: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-05-29 08:06:08.741459 :9D794B7B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:06:38.741083 :9D79555F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:06:38.730 2025-05-29 08:06:38.741084 :9D795560:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14556 pso_num=24 pso_serial#=17 2025-05-29 08:06:38.741222 :9D795561:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14556 osp_idx=22 osp_ver=338931 osp_pg=0 (spawn #230546) 2025-05-29 08:06:38.741223 :9D795562:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:06:38.741223 :9D795563:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=154 time=1481770985 2025-05-29 08:06:38.741224 :9D795564:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:06:38.741224 :9D795565:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:06:38.741224 :9D795566:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:06:38.741225 :9D795567:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:06:38.741225 :9D795568:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:06:38.741703 :9D795569: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-05-29 08:07:08.857193 :9D795ED0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:07:38.845072 :9D796883:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:07:38.834 2025-05-29 08:07:38.845074 :9D796884:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11212 pso_num=24 pso_serial#=18 2025-05-29 08:07:38.845204 :9D796885:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11212 osp_idx=22 osp_ver=338932 osp_pg=0 (spawn #230548) 2025-05-29 08:07:38.845204 :9D796886:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:07:38.845205 :9D796887:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=155 time=1481831094 2025-05-29 08:07:38.845205 :9D796888:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:07:38.845205 :9D796889:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:07:38.845206 :9D79688A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:07:38.845206 :9D79688B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:07:38.845206 :9D79688C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:07:38.845698 :9D79688D: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-05-29 08:08:08.934300 :9D797263:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:08:38.937269 :9D797C17:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:08:38.927 2025-05-29 08:08:38.937271 :9D797C18:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11272 pso_num=24 pso_serial#=19 2025-05-29 08:08:38.937392 :9D797C19:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11272 osp_idx=22 osp_ver=338933 osp_pg=0 (spawn #230550) 2025-05-29 08:08:38.937392 :9D797C1A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:08:38.937393 :9D797C1B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=156 time=1481891188 2025-05-29 08:08:38.937393 :9D797C1C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:08:38.937394 :9D797C1D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:08:38.937394 :9D797C1E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:08:38.937394 :9D797C1F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:08:38.937394 :9D797C20:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:08:38.937881 :9D797C21: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-05-29 08:09:09.024276 :9D7985D0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:09:39.007803 :9D798F79:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:09:38.996 2025-05-29 08:09:39.007806 :9D798F7A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7672 pso_num=24 pso_serial#=20 2025-05-29 08:09:39.007977 :9D798F7B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7672 osp_idx=22 osp_ver=338934 osp_pg=0 (spawn #230552) 2025-05-29 08:09:39.007978 :9D798F7C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:09:39.007979 :9D798F7D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=157 time=1481951250 2025-05-29 08:09:39.007979 :9D798F7E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:09:39.007980 :9D798F7F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:09:39.007980 :9D798F80:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:09:39.007980 :9D798F81:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:09:39.007980 :9D798F82:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:09:39.008467 :9D798F83: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-05-29 08:10:09.070313 :9D7998EF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:10:39.109702 :9D79A2AE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:10:39.098 2025-05-29 08:10:39.109704 :9D79A2AF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15200 pso_num=24 pso_serial#=21 2025-05-29 08:10:39.109828 :9D79A2B0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15200 osp_idx=22 osp_ver=338935 osp_pg=0 (spawn #230554) 2025-05-29 08:10:39.109829 :9D79A2B1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:10:39.109829 :9D79A2B2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=158 time=1482011360 2025-05-29 08:10:39.109830 :9D79A2B3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:10:39.109830 :9D79A2B4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:10:39.109830 :9D79A2B5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:10:39.109831 :9D79A2B6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:10:39.109831 :9D79A2B7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:10:39.110321 :9D79A2B8: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-05-29 08:11:09.183109 :9D79AC62:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:11:39.196293 :9D79B659:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:11:39.185 2025-05-29 08:11:39.196295 :9D79B65A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10020 pso_num=24 pso_serial#=22 2025-05-29 08:11:39.196422 :9D79B65B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10020 osp_idx=22 osp_ver=338936 osp_pg=0 (spawn #230557) 2025-05-29 08:11:39.196423 :9D79B65C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:11:39.196423 :9D79B65D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=159 time=1482071438 2025-05-29 08:11:39.196423 :9D79B65E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:11:39.196424 :9D79B65F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:11:39.196424 :9D79B660:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:11:39.196424 :9D79B661:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:11:39.196425 :9D79B662:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:11:39.196928 :9D79B663: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-05-29 08:12:09.285856 :9D79C007:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:12:39.286448 :9D79CD08:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:12:39.275 2025-05-29 08:12:39.286449 :9D79CD09:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13156 pso_num=24 pso_serial#=23 2025-05-29 08:12:39.286581 :9D79CD0A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13156 osp_idx=22 osp_ver=338937 osp_pg=0 (spawn #230559) 2025-05-29 08:12:39.286581 :9D79CD0B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:12:39.286582 :9D79CD0C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=160 time=1482131532 2025-05-29 08:12:39.286582 :9D79CD0D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:12:39.286583 :9D79CD0E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:12:39.286583 :9D79CD0F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:12:39.286583 :9D79CD10:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:12:39.286584 :9D79CD11:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:12:39.287051 :9D79CD12: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-05-29 08:13:09.390226 :9D79D777:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:13:14.380152 :9D79D956:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:13:14.369 2025-05-29 08:13:14.380155 :9D79D957:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12548 pso_num=24 pso_serial#=24 2025-05-29 08:13:14.380286 :9D79D958:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12548 osp_idx=22 osp_ver=338938 osp_pg=0 (spawn #230561) 2025-05-29 08:13:14.380287 :9D79D959:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:13:14.380287 :9D79D95A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=161 time=1482166625 2025-05-29 08:13:14.380288 :9D79D95B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:13:14.380288 :9D79D95C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:13:14.380288 :9D79D95D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:13:14.380289 :9D79D95E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:13:14.380289 :9D79D95F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:13:14.380751 :9D79D960: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-05-29 08:13:39.416386 :9D79E162: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-05-29 08:13:39.416419*:9D79E163:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002494BB58 estart=1748477618 eid=16883306 ctx=0x000000077BD98A08 cwh=0x00000000232801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-29 08:13:39.416419*:9D79E164:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002494BB58 curCtx=0x000000002328F708 pubTabIdxCnt=0 2025-05-29 08:13:39.416419*:9D79E165:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002494BB58 2025-05-29 08:13:39.416419*:9D79E166:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002494BB58 2025-05-29 08:13:39.416419*:9D79E167:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002494BB58 curCtx=0x000000002328F708 xsc->flg4=65792 flags=2 2025-05-29 08:13:39.416419*:9D79E168:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002494BB58 2025-05-29 08:13:39.417010 :9D79E169: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-05-29 08:13:39.424627 :9D79E171:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-29 08:13:39.424982 :9D79E172: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-05-29 08:13:39.425018 :9D79E175:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-29 08:13:39.425852 :9D79E17B: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-05-29 08:13:39.425975 :9D79E184: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-05-29 08:14:09.519806 :9D79EB33:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:14:39.534728 :9D79F509:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:14:39.523 2025-05-29 08:14:39.534729 :9D79F50A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8928 pso_num=24 pso_serial#=25 2025-05-29 08:14:39.534866 :9D79F50B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8928 osp_idx=22 osp_ver=338939 osp_pg=0 (spawn #230563) 2025-05-29 08:14:39.534867 :9D79F50C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:14:39.534867 :9D79F50D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=162 time=1482251782 2025-05-29 08:14:39.534868 :9D79F50E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:14:39.534868 :9D79F50F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:14:39.534869 :9D79F510:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:14:39.534869 :9D79F511:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:14:39.534869 :9D79F512:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:14:39.535359 :9D79F513: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-05-29 08:15:09.603335 :9D79FED3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:15:23.307659 :9D7A3A02:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:15:23.294 2025-05-29 08:15:23.307662 :9D7A3A03:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14516 pso_num=24 pso_serial#=26 2025-05-29 08:15:23.307804 :9D7A3A04:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14516 (legacy spawn) 2025-05-29 08:15:23.314291 :9D7A3A05: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-05-29 08:15:23.314632 :9D7A3A0E: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-05-29 08:15:23.315485 :9D7A3A0F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:15:25.588699 :9D7A3DB1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:15:25.585 2025-05-29 08:15:25.588701 :9D7A3DB2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14888 pso_num=24 pso_serial#=27 2025-05-29 08:15:25.588859 :9D7A3DB3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14888 osp_idx=22 osp_ver=338941 osp_pg=0 (spawn #230566) 2025-05-29 08:15:25.588859 :9D7A3DB4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:15:25.588860 :9D7A3DB5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=163 time=1482297829 2025-05-29 08:15:25.588860 :9D7A3DB6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:15:25.588860 :9D7A3DB7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:15:25.588861 :9D7A3DB8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:15:25.588861 :9D7A3DB9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:15:25.588862 :9D7A3DBA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:15:25.589812 :9D7A3DBB: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-05-29 08:15:25.589854 :9D7A3DC1: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-05-29 08:15:25.716063 :9D7A3DF3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-29 08:15:27.880888 :9D7A3F0C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-29 08:15:27.877 2025-05-29 08:15:27.880891 :9D7A3F0D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14080 pso_num=24 pso_serial#=28 2025-05-29 08:15:27.881027 :9D7A3F0E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14080 osp_idx=22 osp_ver=338942 osp_pg=0 (spawn #230568) 2025-05-29 08:15:27.881027 :9D7A3F0F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 08:15:27.881028 :9D7A3F10:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=164 time=1482300125 2025-05-29 08:15:27.881028 :9D7A3F11:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 08:15:27.881029 :9D7A3F12:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 08:15:27.881029 :9D7A3F13:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 08:15:27.881030 :9D7A3F14:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 08:15:27.881030 :9D7A3F15:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 08:15:27.881563 :9D7A3F16: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-05-29 08:15:27.881614 :9D7A3F1C: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: 14080, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de