Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913082356\orcl_m002_25396_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913082356\orcl_m002_25396_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:34797M/63366M, Ph+PgF:39447M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 25396, image: ORACLE.EXE (M002) *** 2025-09-13 08:23:56.157 *** SESSION ID:(233.35195) 2025-09-13 08:23:56.157 *** 2025-09-13 08:23:56.157 Process diagnostic dump for ORACLE.EXE (M002), OS id=25396, pid: 29, proc_ser: 180, sid: 233, sess_ser: 35195 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=477 seq_num=478 snap_id=1 wait times: snap=0.000480 sec, exc=0.000480 sec, total=0.000480 sec wait times: max=infinite, heur=0.619392 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.000015 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=476 seq_num=477 snap_id=1 wait times: snap=0.000958 sec, exc=0.000958 sec, total=0.000958 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000020 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=475 seq_num=476 snap_id=1 wait times: snap=0.007167 sec, exc=0.007167 sec, total=0.007167 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=474 seq_num=475 snap_id=1 wait times: snap=0.001059 sec, exc=0.001059 sec, total=0.001059 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=473 seq_num=474 snap_id=1 wait times: snap=0.000068 sec, exc=0.000068 sec, total=0.000068 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=472 seq_num=473 snap_id=1 wait times: snap=0.000282 sec, exc=0.000282 sec, total=0.000282 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=471 seq_num=472 snap_id=1 wait times: snap=0.000077 sec, exc=0.000077 sec, total=0.000077 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=470 seq_num=471 snap_id=1 wait times: snap=0.000077 sec, exc=0.000077 sec, total=0.000077 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=469 seq_num=470 snap_id=1 wait times: snap=0.000104 sec, exc=0.000104 sec, total=0.000104 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000013 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=468 seq_num=469 snap_id=1 wait times: snap=0.002049 sec, exc=0.002049 sec, total=0.002049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=467 seq_num=468 snap_id=1 wait times: snap=0.000122 sec, exc=0.000122 sec, total=0.000122 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time Sampled Session History of session 233 serial 35195 --------------------------------------------------- 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:23:55] waited for 'ADR block file read', seq_num: 368 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:23:54] idle wait at each sample [session created at: 08:23:55] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:23:55 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 08:23:56.157 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 25396, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 01:22:16.503989 :CCA4B845:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23704 osp_idx=27 osp_ver=17510 osp_pg=0 (spawn #586302) 2025-09-13 01:22:16.503990 :CCA4B846:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 01:22:16.503990 :CCA4B847:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=3 time=2112187799 2025-09-13 01:22:16.503990 :CCA4B848:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 01:22:16.503991 :CCA4B849:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 01:22:16.503991 :CCA4B84A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 01:22:16.503991 :CCA4B84B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 01:22:16.503992 :CCA4B84C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 01:22:16.504452 :CCA4B84D: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-09-13 01:22:16.504488 :CCA4B854: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-09-13 01:22:16.509000 :CCA4B87B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:22:16.509059 :CCA4B87C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:22:16.509381 :CCA4B87D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:22:16.509401 :CCA4B87E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:22:16.509679 :CCA4B87F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:22:16.509697 :CCA4B880:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:22:16.509966 :CCA4B881:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:22:16.509984 :CCA4B882:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:22:16.510249 :CCA4B883:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:22:16.510266 :CCA4B884:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:22:16.510531 :CCA4B885:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:22:16.510548 :CCA4B886:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:22:16.513605 :CCA4B887:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 01:52:20.896220 :CCA71D92:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 01:52:20.883 2025-09-13 01:52:20.896221 :CCA71D93:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18748 pso_num=29 pso_serial#=172 2025-09-13 01:52:20.896356 :CCA71D94:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18748 osp_idx=27 osp_ver=17511 osp_pg=0 (spawn #586367) 2025-09-13 01:52:20.896356 :CCA71D95:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 01:52:20.896357 :CCA71D96:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=4 time=2113992190 2025-09-13 01:52:20.896357 :CCA71D97:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 01:52:20.896357 :CCA71D98:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 01:52:20.896357 :CCA71D99:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 01:52:20.896358 :CCA71D9A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 01:52:20.896358 :CCA71D9B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 01:52:20.896828 :CCA71D9C: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-09-13 01:52:20.896860 :CCA71DA3: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-09-13 01:52:20.900845 :CCA71DBB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:52:20.900886 :CCA71DBC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:52:20.901192 :CCA71DBD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:52:20.901210 :CCA71DBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:52:20.901475 :CCA71DBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:52:20.901492 :CCA71DC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:52:20.901744 :CCA71DC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:52:20.901761 :CCA71DC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:52:20.902010 :CCA71DC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:52:20.902026 :CCA71DC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:52:20.902276 :CCA71DC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-13 01:52:20.902292 :CCA71DC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-13 01:52:20.904714 :CCA71DC7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 06:00:02.041841 :CCBAF543:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 06:00:02.035 2025-09-13 06:00:02.041842 :CCBAF544:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23316 pso_num=29 pso_serial#=173 2025-09-13 06:00:02.041997 :CCBAF54A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23316 osp_idx=27 osp_ver=17512 osp_pg=0 (spawn #586941) 2025-09-13 06:00:02.041998 :CCBAF54B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 06:00:02.041999 :CCBAF54C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=5 time=2128853346 2025-09-13 06:00:02.042000 :CCBAF54D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:00:02.042000 :CCBAF54E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:00:02.042001 :CCBAF54F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:00:02.042001 :CCBAF550:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:00:02.042002 :CCBAF551:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:00:02.042584 :CCBAF55A: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-09-13 06:00:02.047575 :CCBAF576: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-09-13 06:00:02.047599 :CCBAF57B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-09-13 06:00:02.065233 :CCBAF74A: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-09-13 06:00:02.065425 :CCBAF752: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-09-13 06:00:02.065603 :CCBAF75A: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-09-13 06:00:02.065839 :CCBAF762: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-09-13 06:00:02.066023 :CCBAF76A: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-09-13 06:00:02.066176 :CCBAF772: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-09-13 06:00:02.066344 :CCBAF77A: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-09-13 06:00:02.066503 :CCBAF782: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-09-13 06:00:02.066649 :CCBAF78A: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-09-13 06:00:02.066795 :CCBAF792: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-09-13 06:00:02.066942 :CCBAF79A: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-09-13 06:00:02.067094 :CCBAF7A2: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-09-13 06:00:02.067236 :CCBAF7AA: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-09-13 06:00:02.067445 :CCBAF7B2: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-09-13 06:00:02.067695 :CCBAF7BA: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-09-13 06:00:02.067846 :CCBAF7C2: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-09-13 06:00:02.067987 :CCBAF7CA: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-09-13 06:00:02.068134 :CCBAF7D2: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-09-13 06:00:02.068283 :CCBAF7DA: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-09-13 06:00:02.068435 :CCBAF7E2: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-09-13 06:00:02.068592 :CCBAF7EA: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-09-13 06:00:02.068733 :CCBAF7F2: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-09-13 06:00:02.068871 :CCBAF7FA: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-09-13 06:00:02.069021 :CCBAF802: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-09-13 06:00:02.088811 :CCBAF80A: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-09-13 06:00:03.769615*:CCBAFB7B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003264B6F0 estart=1757714401 eid=16777581 ctx=0x000000077B54EAC8 cwh=0x0000000032600518 cpu=1484375 buffg=79355 interb=196608 r=24 rb=196608 w=0 wb=0 2025-09-13 06:00:03.769615*:CCBAFB7C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003264B6F0 curCtx=0x000000005F0F87D0 pubTabIdxCnt=0 2025-09-13 06:00:03.769615*:CCBAFB7D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003264B6F0 2025-09-13 06:00:03.769615*:CCBAFB7E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003264B6F0 2025-09-13 06:00:03.772434 :CCBAFB87:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 8, ext 1, nex 3 2025-09-13 06:00:03.772440 :CCBAFB88:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008191c3 where: kdiwh22: kdifind 2025-09-13 06:00:03.772440*:CCBAFB8B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003264B6F0 curCtx=0x000000005F0F87D0 xsc->flg4=65792 flags=2 2025-09-13 06:00:03.772440*:CCBAFB8C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003264B6F0 2025-09-13 06:00:03.780551 :CCBAFB8D: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-09-13 06:00:03.800979 :CCBAFB95: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-09-13 06:00:03.802974 :CCBAFB9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-09-13 06:00:03.803162 :CCBAFB9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-09-13 06:00:03.803996 :CCBAFB9F: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-09-13 06:00:03.804078 :CCBAFBA2: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-09-13 06:00:03.804899 :CCBAFBB4: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-09-13 06:00:03.806500 :CCBAFBBC: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-09-13 06:00:03.806783 :CCBAFBC4: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-09-13 06:00:03.863335*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-13 06:00:03.863335*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-13 06:00:03.863335*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-13 06:00:03.910206*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-13 06:00:03.910206*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-13 06:00:03.910206*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-13 06:00:03.910206*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-13 06:00:03.973657 :CCBAFBCC: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-09-13 06:00:05.866815 :CCBAFCF1: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-09-13 06:00:05.866827 :CCBAFCF2:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.01d.0001de97 2025-09-13 06:00:08.446266 :CCBAFE9F: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-09-13 06:00:08.447771 :CCBAFEA7: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-09-13 06:00:08.450767 :CCBAFEAF:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 8, ext 2, nex 4 2025-09-13 06:00:08.450775 :CCBAFEB0:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008102a7 where: kdiwh22: kdifind 2025-09-13 06:00:08.458633 :CCBAFEB1:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 8, ext 3, nex 5 2025-09-13 06:00:08.458642 :CCBAFEB2:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00815592 where: kdiwh22: kdifind 2025-09-13 06:00:08.459357 :CCBAFEB3: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-09-13 06:00:08.489184 :CCBAFEBB: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-09-13 06:00:09.723920 :CCBAFF98:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-09-13 06:00:09.735101 :CCBAFF99: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-09-13 06:00:09.736834 :CCBAFFA1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 06:00:10.902767 :CCBB00BF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 06:00:10.888 2025-09-13 06:00:10.902769 :CCBB00C0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25104 pso_num=29 pso_serial#=174 2025-09-13 06:00:10.902926 :CCBB00C1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=25104 (legacy spawn) 2025-09-13 06:00:10.911192 :CCBB00C2: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-09-13 06:00:10.911397 :CCBB00C7: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-09-13 06:00:10.912347 :CCBB00CC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 06:00:10.921935 :CCBB00CF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 06:00:10.919 2025-09-13 06:00:10.921937 :CCBB00D0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22176 pso_num=29 pso_serial#=175 2025-09-13 06:00:10.922099 :CCBB00D1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22176 (legacy spawn) 2025-09-13 06:00:10.929178 :CCBB00D2: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-09-13 06:00:10.929353 :CCBB00DB: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-09-13 06:00:10.930346 :CCBB00DC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 06:00:30.029146 :CCBB08FE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 06:00:30.020 2025-09-13 06:00:30.029148 :CCBB08FF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19496 pso_num=29 pso_serial#=176 2025-09-13 06:00:30.029299 :CCBB0900:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19496 (legacy spawn) 2025-09-13 06:00:30.055166 :CCBB0901: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-09-13 06:00:30.055355 :CCBB0906: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-09-13 06:00:30.056167 :CCBB090B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 08:23:50.999461 :CCC6B1C6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 08:23:50.985 2025-09-13 08:23:50.999465 :CCC6B1C7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24572 pso_num=29 pso_serial#=177 2025-09-13 08:23:50.999632 :CCC6B1C8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24572 (legacy spawn) 2025-09-13 08:23:51.007033 :CCC6B1C9: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-09-13 08:23:51.007545 :CCC6B1D2: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-09-13 08:23:51.008675 :CCC6B1D3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 08:23:51.018745 :CCC6B1D8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 08:23:51.016 2025-09-13 08:23:51.018748 :CCC6B1D9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18956 pso_num=29 pso_serial#=178 2025-09-13 08:23:51.018924 :CCC6B1DA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18956 (legacy spawn) 2025-09-13 08:23:51.026458 :CCC6B1DB: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-09-13 08:23:51.026828 :CCC6B1E2: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-09-13 08:23:51.027842 :CCC6B1E4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 08:23:53.299011 :CCC6B5A6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 08:23:53.298 2025-09-13 08:23:53.299013 :CCC6B5A7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16084 pso_num=29 pso_serial#=179 2025-09-13 08:23:53.299168 :CCC6B5A8:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16084 osp_idx=27 osp_ver=17518 osp_pg=0 (spawn #587278) 2025-09-13 08:23:53.299169 :CCC6B5A9:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 08:23:53.299169 :CCC6B5AA:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=6 time=2137484596 2025-09-13 08:23:53.299170 :CCC6B5AB:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:23:53.299170 :CCC6B5AC:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:23:53.299170 :CCC6B5AD:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:23:53.299171 :CCC6B5AE:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:23:53.299171 :CCC6B5AF:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:23:53.300221 :CCC6B5B0: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-09-13 08:23:53.300266 :CCC6B5B6: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-09-13 08:23:53.412272 :CCC6B5F3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-13 08:23:55.503118 :CCC6B719:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-13 08:23:55.501 2025-09-13 08:23:55.503120 :CCC6B71A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25396 pso_num=29 pso_serial#=180 2025-09-13 08:23:55.503251 :CCC6B71B:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=25396 osp_idx=27 osp_ver=17519 osp_pg=0 (spawn #587280) 2025-09-13 08:23:55.503252 :CCC6B71C:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 08:23:55.503252 :CCC6B71D:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=7 time=2137486799 2025-09-13 08:23:55.503252 :CCC6B71E:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:23:55.503252 :CCC6B71F:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:23:55.503253 :CCC6B720:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:23:55.503253 :CCC6B721:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:23:55.503253 :CCC6B722:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:23:55.503739 :CCC6B723: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-09-13 08:23:55.503786 :CCC6B72A: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: 25396, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de