Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250428221612\orcl_m000_9532_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:37579M/63366M, Ph+PgF:45221M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9532, image: ORACLE.EXE (M000) *** 2025-04-28 22:16:12.987 *** SESSION ID:(193.42035) 2025-04-28 22:16:12.987 *** 2025-04-28 22:16:12.987 Process diagnostic dump for ORACLE.EXE (M000), OS id=9532, pid: 24, proc_ser: 53, sid: 193, sess_ser: 42035 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.078572 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.078597 sec since last wait 0: waited for 'latch: cache buffers chains' address=0x7a8c919e8, number=0x96, tries=0x0 wait_id=215 seq_num=216 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.169825 sec of elapsed time 1: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x0, timeout=0xffffffff wait_id=214 seq_num=215 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.000006 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x2, filetype=0x2 wait_id=213 seq_num=214 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.000005 sec of elapsed time 3: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x2, filetype=0x2 wait_id=212 seq_num=213 snap_id=1 wait times: snap=0.000211 sec, exc=0.000211 sec, total=0.000211 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 1.293161 sec of elapsed time 4: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=211 seq_num=212 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 5: waited for 'control file sequential read' file#=0x0, block#=0x17, blocks=0x1 wait_id=210 seq_num=211 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time 6: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=209 seq_num=210 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 7: waited for 'control file sequential read' file#=0x0, block#=0x10, blocks=0x1 wait_id=208 seq_num=209 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 8: waited for 'control file sequential read' file#=0x1, block#=0x1, blocks=0x1 wait_id=207 seq_num=208 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x1, blocks=0x1 wait_id=206 seq_num=207 snap_id=1 wait times: snap=0.000097 sec, exc=0.000097 sec, total=0.000097 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time Sampled Session History of session 193 serial 42035 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:16:08 - 22:16:12] not in wait at each sample [1 sample, 22:16:07] idle wait at each sample [session created at: 22:16:07] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-28 22:16:12.987 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9532, M000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-28 22:16:08.434441 :9017BA59: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-04-28 22:16:08.475563 :9017BA61: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-04-28 22:16:08.497495 :9017BA69:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 19, nex 21 2025-04-28 22:16:08.497503 :9017BA6A:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0081312a where: kddwh01: kdddel 2025-04-28 22:16:08.516461 :9017BA6B: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-04-28 22:16:08.569761 :9017BA73: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-04-28 22:16:08.569802 :9017BA76: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-04-28 22:16:08.602460 :9017BA88:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 20, nex 22 2025-04-28 22:16:08.602471 :9017BA89:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00814227 where: kddwh01: kdddel 2025-04-28 22:16:08.627158 :9017BA8A: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-04-28 22:16:08.684169 :9017BA92: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-04-28 22:16:08.684212 :9017BA95: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-04-28 22:16:08.717759 :9017BAA7:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 21, nex 23 2025-04-28 22:16:08.717770 :9017BAA8:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008118d4 where: kddwh01: kdddel 2025-04-28 22:16:08.742004 :9017BAA9: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-04-28 22:16:08.742045 :9017BAAC: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-04-28 22:16:08.796262 :9017BABE: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-04-28 22:16:08.796301 :9017BAC1: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-04-28 22:16:08.822639 :9017BAD3:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 22, nex 24 2025-04-28 22:16:08.822649 :9017BAD4:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008109c2 where: kddwh01: kdddel 2025-04-28 22:16:08.840716 :9017BAD5: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-04-28 22:16:08.840757 :9017BAD8: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-04-28 22:16:08.885202 :9017BAEA: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-04-28 22:16:08.911602 :9017BAF2:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 23, nex 25 2025-04-28 22:16:08.911612 :9017BAF3:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00811dfe where: kddwh01: kdddel 2025-04-28 22:16:08.929405 :9017BAF4: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-04-28 22:16:08.929443 :9017BAF7: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-04-28 22:16:08.974237 :9017BB09: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-04-28 22:16:09.000829 :9017BB11:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 24, nex 26 2025-04-28 22:16:09.000838 :9017BB12:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f0b where: kddwh01: kdddel 2025-04-28 22:16:09.018469 :9017BB13: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-04-28 22:16:09.062490 :9017BB1B: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-04-28 22:16:09.089882 :9017BB2E:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 25, nex 27 2025-04-28 22:16:09.089891 :9017BB2F:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f1e where: kddwh01: kdddel 2025-04-28 22:16:09.106979 :9017BB30: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-04-28 22:16:09.150980 :9017BB44: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-04-28 22:16:09.178637 :9017BB54:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 26, nex 28 2025-04-28 22:16:09.178646 :9017BB55:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f31 where: kddwh01: kdddel 2025-04-28 22:16:09.195265 :9017BB56: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-04-28 22:16:09.195304 :9017BB59: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-04-28 22:16:09.195859 :9017BB60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-28 22:16:09.240118 :9017BB67: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-04-28 22:16:09.268613 :9017BB7F:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 27, nex 29 2025-04-28 22:16:09.268623 :9017BB80:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f44 where: kddwh01: kdddel 2025-04-28 22:16:09.302738 :9017BB81: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-04-28 22:16:09.315816*:9017BB89:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002475C4B0 estart=1745849768 eid=16777251 ctx=0x00000007738B9F78 cwh=0x00000000246801B0 cpu=953125 buffg=166993 interb=0 r=0 rb=0 w=0 wb=0 2025-04-28 22:16:09.315816*:9017BB8A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002475C4B0 curCtx=0x0000000026B4FE68 pubTabIdxCnt=0 2025-04-28 22:16:09.315816*:9017BB8B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002475C4B0 2025-04-28 22:16:09.315816*:9017BB8C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002475C4B0 2025-04-28 22:16:09.337600 :9017BB8D: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-04-28 22:16:09.339021 :9017BB93: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-04-28 22:16:09.339044 :9017BB94: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-04-28 22:16:09.340742 :9017BB97: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-04-28 22:16:09.341187 :9017BBB5: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-04-28 22:16:09.359619 :9017BBCD:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 28, nex 30 2025-04-28 22:16:09.359628 :9017BBCE:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f58 where: kddwh01: kdddel 2025-04-28 22:16:09.382991 :9017BBCF: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-04-28 22:16:09.423947 :9017BBD7: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-04-28 22:16:09.423977 :9017BBDA: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-04-28 22:16:09.442496 :9017BBEC:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 29, nex 31 2025-04-28 22:16:09.442504 :9017BBED:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f6b where: kddwh01: kdddel 2025-04-28 22:16:09.465978 :9017BBEE: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-04-28 22:16:09.506878 :9017BBF6: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-04-28 22:16:09.506910 :9017BBF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-28 22:16:09.525865 :9017BC00:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 30, nex 32 2025-04-28 22:16:09.525873 :9017BC01:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00825f7e where: kddwh01: kdddel 2025-04-28 22:16:09.554871 :9017BC03: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-04-28 22:16:09.554912 :9017BC06: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-04-28 22:16:09.613241 :9017BC18: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-04-28 22:16:09.639729 :9017BC20:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 31, nex 33 2025-04-28 22:16:09.639743 :9017BC21:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00864b93 where: kddwh01: kdddel 2025-04-28 22:16:09.671143 :9017BC22: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-04-28 22:16:09.729119 :9017BC2A: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-04-28 22:16:09.756438 :9017BC32:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 32, nex 34 2025-04-28 22:16:09.756450 :9017BC33:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00864ba6 where: kddwh01: kdddel 2025-04-28 22:16:09.787181 :9017BC34: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-04-28 22:16:09.787225 :9017BC37: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-04-28 22:16:09.833322 :9017BC49: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-04-28 22:16:09.854474 :9017BC51:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 33, nex 35 2025-04-28 22:16:09.854483 :9017BC52:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00864bb9 where: kddwh01: kdddel 2025-04-28 22:16:09.877649 :9017BC53: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-04-28 22:16:09.921574 :9017BC5B: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-04-28 22:16:09.943115 :9017BC63:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 34, nex 36 2025-04-28 22:16:09.943125 :9017BC64:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00864bcc where: kddwh01: kdddel 2025-04-28 22:16:09.966202 :9017BC65: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-04-28 22:16:09.966243 :9017BC68: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-04-28 22:16:10.010475 :9017BCA9: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-04-28 22:16:10.032053 :9017BCB1:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 35, nex 37 2025-04-28 22:16:10.032063 :9017BCB2:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00864be0 where: kddwh01: kdddel 2025-04-28 22:16:10.055018 :9017BCBE: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-04-28 22:16:10.098645 :9017BCC6: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-04-28 22:16:10.120728 :9017BCCE:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 36, nex 38 2025-04-28 22:16:10.120738 :9017BCCF:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00864bf3 where: kddwh01: kdddel 2025-04-28 22:16:10.143262 :9017BCDC: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-04-28 22:16:10.187644 :9017BCE4: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-04-28 22:16:10.210338 :9017BCEC:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 37, nex 39 2025-04-28 22:16:10.210348 :9017BCED:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087fa88 where: kddwh01: kdddel 2025-04-28 22:16:10.233437 :9017BCF1: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-04-28 22:16:10.300354 :9017BD15: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-04-28 22:16:10.302683 :9017BD1D:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 38, nex 40 2025-04-28 22:16:10.302693 :9017BD1E:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087fa9b where: kddwh01: kdddel 2025-04-28 22:16:10.345168 :9017BD1F: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-04-28 22:16:10.389491 :9017BD46: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-04-28 22:16:10.392313 :9017BD4E:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 39, nex 41 2025-04-28 22:16:10.392323 :9017BD4F:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087faae where: kddwh01: kdddel 2025-04-28 22:16:10.434003 :9017BD50: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-04-28 22:16:10.478209 :9017BD58: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-04-28 22:16:10.481631 :9017BD60:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 40, nex 42 2025-04-28 22:16:10.481641 :9017BD61:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087fac1 where: kddwh01: kdddel 2025-04-28 22:16:10.522982 :9017BD62: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-04-28 22:16:10.566708 :9017BD8D: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-04-28 22:16:10.570755 :9017BD95:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 41, nex 43 2025-04-28 22:16:10.570764 :9017BD96:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087fad5 where: kddwh01: kdddel 2025-04-28 22:16:10.611265 :9017BD97: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-04-28 22:16:10.653790 :9017BD9F: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-04-28 22:16:10.657899 :9017BDA7:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 42, nex 44 2025-04-28 22:16:10.657907 :9017BDA8:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087fae8 where: kddwh01: kdddel 2025-04-28 22:16:10.695497 :9017BDD1: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-04-28 22:16:10.736789 :9017BDD9: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-04-28 22:16:10.741263 :9017BDE1:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 43, nex 45 2025-04-28 22:16:10.741271 :9017BDE2:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0087fafb where: kddwh01: kdddel 2025-04-28 22:16:10.778377 :9017BDE3: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-04-28 22:16:10.819571 :9017BDEB: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-04-28 22:16:10.819602 :9017BDEE: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-04-28 22:16:10.820139 :9017BDF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-28 22:16:10.824718 :9017BDFC:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 44, nex 46 2025-04-28 22:16:10.824726 :9017BDFD:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008a2790 where: kddwh01: kdddel 2025-04-28 22:16:10.861533 :9017BDFE: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-04-28 22:16:10.905226 :9017BE06: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-04-28 22:16:10.910887 :9017BE0E:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 45, nex 47 2025-04-28 22:16:10.910900 :9017BE0F:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008a27a3 where: kddwh01: kdddel 2025-04-28 22:16:10.950070 :9017BE10: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-04-28 22:16:10.994394 :9017BE18: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-04-28 22:16:11.000623 :9017BE20:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 46, nex 48 2025-04-28 22:16:11.000635 :9017BE21:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008a27b6 where: kddwh01: kdddel 2025-04-28 22:16:11.039097 :9017BE22: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-04-28 22:16:11.083615 :9017BE2A: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-04-28 22:16:11.090471 :9017BE32:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 47, nex 49 2025-04-28 22:16:11.090481 :9017BE33:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008a27ca where: kddwh01: kdddel 2025-04-28 22:16:11.128477 :9017BE34: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-04-28 22:16:11.170519 :9017BE5B: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-04-28 22:16:11.170986 :9017BE5D: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-04-28 22:16:11.172625 :9017BE60: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-04-28 22:16:11.173006 :9017BE79: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-04-28 22:16:11.181632 :9017BE96:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 48, nex 50 2025-04-28 22:16:11.181640 :9017BE97:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008a27dd where: kddwh01: kdddel 2025-04-28 22:16:11.215516 :9017BE98: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-04-28 22:16:11.215546 :9017BE9B: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-04-28 22:16:11.256814 :9017BEAD: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-04-28 22:16:11.264668 :9017BEB5:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 49, nex 51 2025-04-28 22:16:11.264676 :9017BEB6:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008a27f0 where: kddwh01: kdddel 2025-04-28 22:16:11.298348 :9017BEB7: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-04-28 22:16:11.298380 :9017BEBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-28 22:16:11.325777*:9017BEC1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002475C4B0 estart=1745849768 eid=16777251 ctx=0x00000007738B9F78 cwh=0x00000000246801B0 cpu=2015625 buffg=368119 interb=0 r=0 rb=0 w=0 wb=0 2025-04-28 22:16:11.325777*:9017BEC2:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002475C4B0 2025-04-28 22:16:11.340767 :9017BEC3: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-04-28 22:16:11.340797 :9017BEC6: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-04-28 22:16:11.341303 :9017BECD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-28 22:16:11.349099 :9017BEDA:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 50, nex 52 2025-04-28 22:16:11.349109 :9017BEDB:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x008c12a8 where: kddwh01: kdddel 2025-04-28 22:16:11.385751 :9017BEDC: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-04-28 22:16:11.403905*:9017BEE4:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002475C4B0 curCtx=0x0000000026B4FE68 xsc->flg4=65792 flags=2 2025-04-28 22:16:11.403905*:9017BEE5:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002475C4B0 2025-04-28 22:16:11.429072 :9017BEE6: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-04-28 22:16:11.436424 :9017BEEE:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 3, ext 51, nex 53 2025-04-28 22:16:11.436432 :9017BEEF:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x0080214b where: kddwh01: kdddel 2025-04-28 22:16:11.447621 :9017BEF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=16 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:16:12.791027 :9017BFC6: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-04-28 22:16:12.833447 :9017BFCE: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-04-28 22:16:12.875785 :9017BFD6: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-04-28 22:16:12.875807 :9017BFD9: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-04-28 22:16:12.911921 :9017C07A:db_trace:ksl2.c@2477:kslwtbctx(): [10005:24:193] KSL WAIT BEG [latch: cache buffers chains] 32896522728/0x7a8c919e8 150/0x96 0/0x0 wait_id=215 seq_num=216 snap_id=1 2025-04-28 22:16:12.911925 :9017C07B:db_trace:ksl2.c@2611:kslwtectx(): [10005:24:193] KSL WAIT END [latch: cache buffers chains] 32896522728/0x7a8c919e8 150/0x96 0/0x0 wait_id=215 seq_num=216 snap_id=1 2025-04-28 22:16:12.911925 :9017C07C:db_trace:ksl2.c@2619:kslwtectx(): [10005:24:193] KSL WAIT END wait times (usecs) - snap=6, exc=6, tot=6 2025-04-28 22:16:12.919026 :9017C09F: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-04-28 22:16:12.919101 :9017C0A2: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-04-28 22:16:12.971424 :9017C234: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-04-28 22:16:12.971447 :9017C237: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 9532, M000)