Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250724222159\orcl_m002_17492_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250724222159\orcl_m002_17492_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:36104M/63366M, Ph+PgF:41914M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 17492, image: ORACLE.EXE (M002) *** 2025-07-24 22:22:00.094 *** SESSION ID:(233.30053) 2025-07-24 22:22:00.094 *** 2025-07-24 22:22:00.094 Process diagnostic dump for ORACLE.EXE (M002), OS id=17492, pid: 29, proc_ser: 184, sid: 233, sess_ser: 30053 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=402 seq_num=403 snap_id=1 wait times: snap=0.000918 sec, exc=0.000918 sec, total=0.000918 sec wait times: max=infinite, heur=0.339059 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.000022 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=401 seq_num=402 snap_id=1 wait times: snap=0.005625 sec, exc=0.005625 sec, total=0.005625 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=400 seq_num=401 snap_id=1 wait times: snap=0.012126 sec, exc=0.012126 sec, total=0.012126 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=399 seq_num=400 snap_id=1 wait times: snap=0.000125 sec, exc=0.000125 sec, total=0.000125 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=398 seq_num=399 snap_id=1 wait times: snap=0.005857 sec, exc=0.005857 sec, total=0.005857 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000012 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=397 seq_num=398 snap_id=1 wait times: snap=0.000498 sec, exc=0.000498 sec, total=0.000498 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=396 seq_num=397 snap_id=1 wait times: snap=0.006125 sec, exc=0.006125 sec, total=0.006125 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=395 seq_num=396 snap_id=1 wait times: snap=0.000075 sec, exc=0.000075 sec, total=0.000075 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=394 seq_num=395 snap_id=1 wait times: snap=0.006256 sec, exc=0.006256 sec, total=0.006256 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000020 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=393 seq_num=394 snap_id=1 wait times: snap=0.005629 sec, exc=0.005629 sec, total=0.005629 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000021 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=392 seq_num=393 snap_id=1 wait times: snap=0.005637 sec, exc=0.005637 sec, total=0.005637 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time Sampled Session History of session 233 serial 30053 --------------------------------------------------- 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, 22:21:59] not in wait at each sample [1 sample, 22:21:58] idle wait at each sample [session created at: 22:21:59] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-24 22:22:00.094 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 17492, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-24 16:00:17.544249 :B6651727:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 16:00:17.542 2025-07-24 16:00:17.544251 :B6651728:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17856 pso_num=29 pso_serial#=174 2025-07-24 16:00:17.544383 :B6651729:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17856 osp_idx=27 osp_ver=14963 osp_pg=0 (spawn #418320) 2025-07-24 16:00:17.544384 :B665172A:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 16:00:17.544384 :B665172B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=18 time=2053593423 2025-07-24 16:00:17.544385 :B665172C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 16:00:17.544385 :B665172D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 16:00:17.544385 :B665172E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 16:00:17.544385 :B665172F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 16:00:17.544386 :B6651730:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 16:00:17.544895 :B6651731:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.544937 :B6651737:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 16:00:17.552174 :B6651745:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.553203 :B665174D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.553672 :B6651755:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.554106 :B665175D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.554530 :B6651765:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.557068 :B665176D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.558201 :B6651775:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.607680 :B665177D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.608581 :B6651785:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.608820 :B665178D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.609038 :B6651795:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 16:00:17.610849 :B665179D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:02.041539 :B681059D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:02.030 2025-07-24 22:00:02.041541 :B681059E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14024 pso_num=29 pso_serial#=175 2025-07-24 22:00:02.041672 :B68105A2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14024 osp_idx=27 osp_ver=14964 osp_pg=0 (spawn #419152) 2025-07-24 22:00:02.041672 :B68105A3:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:00:02.041673 :B68105A4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=19 time=2075177923 2025-07-24 22:00:02.041673 :B68105A5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:00:02.041673 :B68105A6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:00:02.041674 :B68105A7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:00:02.041674 :B68105A8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:00:02.041675 :B68105A9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:00:02.042239 :B68105B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.047186 :B68105D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.047221 :B68105D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-24 22:00:02.064312 :B681066B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.064531 :B6810673:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.064702 :B681067B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.064863 :B6810683:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.065018 :B681068B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.065180 :B6810693:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.065411 :B681069B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.065574 :B68106A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.065732 :B68106AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.065896 :B68106B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.066059 :B68106BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.066215 :B68106C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.066372 :B68106CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.066587 :B68106D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.066746 :B68106DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.066905 :B68106E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067071 :B68106EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067223 :B68106F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067378 :B68106FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067526 :B6810703:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067676 :B681070B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067837 :B6810713:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.067990 :B681071B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.068670 :B6810723:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.068883 :B681072B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:02.095899 :B6810733:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.773453 :B6810BDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.793708 :B6810BFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.795640 :B6810C06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-24 22:00:03.795867 :B6810C07:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-24 22:00:03.796845 :B6810C08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.796939 :B6810C0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.797889 :B6810C1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.799399 :B6810C25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.799671 :B6810C2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:03.811428*:B6810C3E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000033FA0230 estart=1753365602 eid=16778162 ctx=0x000000079F4EAD08 cwh=0x0000000031F91058 cpu=15625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-24 22:00:03.811428*:B6810C3F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000033FA0230 curCtx=0x00000000512C52F8 pubTabIdxCnt=0 2025-07-24 22:00:03.811428*:B6810C40:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000033FA0230 2025-07-24 22:00:03.811428*:B6810C41:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000033FA0230 2025-07-24 22:00:03.811428*:B6810C61:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000033FA0230 curCtx=0x00000000512C52F8 xsc->flg4=65792 flags=1 2025-07-24 22:00:03.811428*:B6810C62:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000033FA0230 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-24 22:00:03.942521 :B6810CF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:05.833934 :B6810DE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:05.833947 :B6810DE6:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.001.0001d4d0 2025-07-24 22:00:08.202129*:B6810FA8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DCBBB08 estart=1753365607 eid=16777732 ctx=0x000000077F58CBF8 cwh=0x000000002B560428 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-07-24 22:00:08.202129*:B6810FA9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DCBBB08 curCtx=0x00000000511CE7B8 pubTabIdxCnt=0 2025-07-24 22:00:08.202129*:B6810FAA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DCBBB08 2025-07-24 22:00:08.202129*:B6810FAB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DCBBB08 2025-07-24 22:00:08.202129*:B6810FAC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DCBBB08 curCtx=0x00000000511CE7B8 xsc->flg4=65792 flags=1 2025-07-24 22:00:08.202129*:B6810FAD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DCBBB08 2025-07-24 22:00:08.208543 :B6810FAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:08.213897 :B6810FB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:08.228619 :B6810FBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:08.262003 :B6810FC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:09.375051 :B6811077:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-24 22:00:09.388414 :B6811078:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:09.389088 :B6811080:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:27.499156 :B681169F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:27.496 2025-07-24 22:00:27.499158 :B68116A0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9676 pso_num=29 pso_serial#=176 2025-07-24 22:00:27.499296 :B68116A1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9676 (legacy spawn) 2025-07-24 22:00:27.520369 :B68116A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:27.520547 :B68116AB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:00:27.521391 :B68116AC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:27.530143 :B68116AF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:27.527 2025-07-24 22:00:27.530145 :B68116B0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18232 pso_num=29 pso_serial#=177 2025-07-24 22:00:27.530281 :B68116B1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18232 (legacy spawn) 2025-07-24 22:00:27.536732 :B68116B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:27.536900 :B68116BB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:00:27.537677 :B68116BC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:30.530469 :B68117B2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:30.520 2025-07-24 22:00:30.530471 :B68117B3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4284 pso_num=29 pso_serial#=178 2025-07-24 22:00:30.530611 :B68117B4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4284 (legacy spawn) 2025-07-24 22:00:30.553670 :B68117B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:30.553861 :B68117BE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:00:30.554725 :B68117BF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:30.596765 :B68117C2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:30.583 2025-07-24 22:00:30.596767 :B68117C3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4148 pso_num=29 pso_serial#=179 2025-07-24 22:00:30.596906 :B68117C4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4148 (legacy spawn) 2025-07-24 22:00:30.603384 :B68117C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:30.603549 :B68117CE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:00:30.604352 :B68117CF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:30.613170 :B68117D2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:30.599 2025-07-24 22:00:30.613172 :B68117D3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16220 pso_num=29 pso_serial#=180 2025-07-24 22:00:30.613322 :B68117D4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16220 (legacy spawn) 2025-07-24 22:00:30.634718 :B68117D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:00:30.634898 :B68117DE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:00:30.635685 :B68117DF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:01:02.296991 :B68123D7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:01:02.297 2025-07-24 22:01:02.296993 :B68123D8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4904 pso_num=29 pso_serial#=181 2025-07-24 22:01:02.297127 :B68123D9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=4904 osp_idx=27 osp_ver=14970 osp_pg=0 (spawn #419158) 2025-07-24 22:01:02.297128 :B68123DA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:01:02.297128 :B68123DB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=20 time=2075238189 2025-07-24 22:01:02.297129 :B68123DC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:01:02.297129 :B68123DD:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:01:02.297129 :B68123DE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:01:02.297130 :B68123DF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:01:02.297130 :B68123E0:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:01:02.297644 :B68123E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.297681 :B68123E7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:01:02.300242 :B68123F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.305158 :B68123FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.306214 :B6812405:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.306771 :B681240D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.307260 :B6812415:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.307755 :B681241D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.310210 :B6812425:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.311358 :B681242D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.361484 :B6812435:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.362381 :B681243D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.362626 :B6812445:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.362835 :B681244D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:01:02.364605 :B6812455:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:21:58.367938 :B6830C46:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:21:58.360 2025-07-24 22:21:58.367940 :B6830C47:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19280 pso_num=29 pso_serial#=182 2025-07-24 22:21:58.368100 :B6830C48:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19280 osp_idx=27 osp_ver=14971 osp_pg=0 (spawn #419209) 2025-07-24 22:21:58.368101 :B6830C49:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:21:58.368103 :B6830C4A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=21 time=2076494267 2025-07-24 22:21:58.368104 :B6830C4B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:21:58.368104 :B6830C4C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:21:58.368104 :B6830C4D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:21:58.368105 :B6830C4E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:21:58.368105 :B6830C4F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:21:58.369420 :B6830C50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:21:58.369469 :B6830C56:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:21:58.487750 :B6830C88:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:21:58.774591 :B6830C97:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:21:58.766 2025-07-24 22:21:58.774593 :B6830C98:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13400 pso_num=29 pso_serial#=183 2025-07-24 22:21:58.774727 :B6830C99:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13400 (legacy spawn) 2025-07-24 22:21:58.781219 :B6830C9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:58.781400 :B6830C9F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-24 22:21:58.782237 :B6830CA4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:21:59.678853 :B6830CBA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:21:59.672 2025-07-24 22:21:59.678854 :B6830CBB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17492 pso_num=29 pso_serial#=184 2025-07-24 22:21:59.678985 :B6830CBC:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17492 osp_idx=27 osp_ver=14973 osp_pg=0 (spawn #419211) 2025-07-24 22:21:59.678986 :B6830CBD:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:21:59.678986 :B6830CBE:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=22 time=2076495579 2025-07-24 22:21:59.678987 :B6830CBF:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:21:59.678987 :B6830CC0:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:21:59.678988 :B6830CC1:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:21:59.678988 :B6830CC2:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:21:59.678988 :B6830CC3:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:21:59.679505 :B6830CC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:21:59.679546 :B6830CCA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 29 (osid: 17492, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de