Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250704220628\orcl_m002_17560_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:37243M/63366M, Ph+PgF:43235M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17560, image: ORACLE.EXE (M002) *** 2025-07-04 22:06:28.575 *** SESSION ID:(201.10243) 2025-07-04 22:06:28.575 *** 2025-07-04 22:06:28.575 Process diagnostic dump for ORACLE.EXE (M002), OS id=17560, pid: 25, proc_ser: 150, sid: 201, sess_ser: 10243 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=369 seq_num=370 snap_id=1 wait times: snap=0.003314 sec, exc=0.003314 sec, total=0.003314 sec wait times: max=infinite, heur=0.216136 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.000018 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=368 seq_num=369 snap_id=1 wait times: snap=0.004764 sec, exc=0.004764 sec, total=0.004764 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=367 seq_num=368 snap_id=1 wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000020 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=366 seq_num=367 snap_id=1 wait times: snap=0.006325 sec, exc=0.006325 sec, total=0.006325 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000021 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=365 seq_num=366 snap_id=1 wait times: snap=0.005633 sec, exc=0.005633 sec, total=0.005633 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=364 seq_num=365 snap_id=1 wait times: snap=0.002420 sec, exc=0.002420 sec, total=0.002420 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=363 seq_num=364 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.000020 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=362 seq_num=363 snap_id=1 wait times: snap=0.006215 sec, exc=0.006215 sec, total=0.006215 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000020 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=361 seq_num=362 snap_id=1 wait times: snap=0.005664 sec, exc=0.005664 sec, total=0.005664 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=360 seq_num=361 snap_id=1 wait times: snap=0.007175 sec, exc=0.007175 sec, total=0.007175 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=359 seq_num=360 snap_id=1 wait times: snap=0.003568 sec, exc=0.003568 sec, total=0.003568 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time Sampled Session History of session 201 serial 10243 --------------------------------------------------- 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:06:28] idle wait at each sample [session created at: 22:06:28] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-04 22:06:28.575 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17560, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-04 22:00:02.025497*:AD936FF2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-07-04 22:00:02.025497*:AD936FF3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-07-04 22:00:02.030057 :AD936FF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.030070 :AD936FF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.030132 :AD936FF8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-04 22:00:02.030157 :AD936FFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.030157*:AD936FFD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-07-04 22:00:02.030157*:AD936FFE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-07-04 22:00:02.030596 :AD93700B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.030638 :AD93700E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-04 22:00:02.034232 :AD937037:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.417079 :AD9377E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.460755 :AD9377F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.476611 :AD9377FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.477099 :AD937806:db_trace:ktu.c@4871:ktuaex1r(): [10442:25:201] Extension usn 7, ext 1, nex 2 2025-07-04 22:00:02.534936 :AD937807:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.669436 :AD93782F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.786762 :AD9378FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.796711 :AD937905:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.812469 :AD937936:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.828955 :AD937959:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.844450 :AD937961:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.854094 :AD937969:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.869130 :AD937971:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.878467 :AD937979:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.893833 :AD937981:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.903611 :AD937989:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.912921 :AD937991:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.927777 :AD937999:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.937171 :AD9379A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.952684 :AD9379B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.968032 :AD9379BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.983165 :AD9379C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:02.998407 :AD9379CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.008265 :AD9379D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.023585 :AD9379DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.033650 :AD9379E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.043566 :AD9379ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.052819 :AD9379F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.068439 :AD9379FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.083585 :AD937A05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.090656 :AD937A0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.100441 :AD937A15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.111463 :AD937A1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.126760 :AD937A25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.141912 :AD937A2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.149343 :AD937A35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.158408 :AD937A3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.168107 :AD937A45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.183376 :AD937A4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.198431 :AD937A55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.213473 :AD937A5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.225636 :AD937A65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.235099 :AD937A6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.244589 :AD937A75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.254616 :AD937A7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.264165 :AD937A85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.279573 :AD937A8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.287454 :AD937A95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.295120 :AD937A9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.302703 :AD937AA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.310284 :AD937AAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.317681 :AD937AB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.325210 :AD937ABD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.332857 :AD937AC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.340405 :AD937ACD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.348092 :AD937AD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.355810 :AD937ADD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.363319 :AD937AE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.371021 :AD937AED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.378853 :AD937AF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:03.726356 :AD937B30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] 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-07-04 22:00:03.848177*:AD937BBB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB56918 estart=1751637602 eid=16777421 ctx=0x00000007831EA738 cwh=0x000000002DB80248 cpu=62500 buffg=74 interb=1040384 r=1 rb=1040384 w=0 wb=0 2025-07-04 22:00:03.848177*:AD937BBC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB56918 curCtx=0x000000004BF99DB0 pubTabIdxCnt=0 2025-07-04 22:00:03.848177*:AD937BBD:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB56918 2025-07-04 22:00:03.848177*:AD937BBE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB56918 2025-07-04 22:00:03.848177*:AD937BC0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC288 estart=1751637601 eid=16777318 ctx=0x000000079F654AA8 cwh=0x0000000026BE0158 cpu=0 buffg=144654 interb=34398208 r=1580 rb=29810688 w=24 wb=4587520 2025-07-04 22:00:03.848177*:AD937BC3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC288 curCtx=0x000000002DB58510 pubTabIdxCnt=0 2025-07-04 22:00:03.848177*:AD937BC4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC288 2025-07-04 22:00:03.848177*:AD937BC5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC288 2025-07-04 22:00:03.926317*:AD937C1C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB56918 curCtx=0x000000004BF99DB0 xsc->flg4=65792 flags=1 2025-07-04 22:00:03.926317*:AD937C1D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB56918 2025-07-04 22:00:04.550791 :AD937D29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:04.551218 :AD937D31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:04.551218*:AD937D34:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC288 curCtx=0x000000002DB58510 xsc->flg4=65792 flags=2 2025-07-04 22:00:04.551218*:AD937D35:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC288 2025-07-04 22:00:04.551507 :AD937D3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-04 22:00:04.556034 :AD937D3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:04.556121 :AD937D3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-04 22:00:04.556180 :AD937D46:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-04 22:00:04.556182 :AD937D47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-04 22:00:04.556204 :AD937D4E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04 22:00:34.642311 :AD93890D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:00:39.718318 :AD938AD7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:00:39.706 2025-07-04 22:00:39.718320 :AD938AD8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15584 pso_num=25 pso_serial#=143 2025-07-04 22:00:39.718458 :AD938ADC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15584 osp_idx=23 osp_ver=182325 osp_pg=0 (spawn #352492) 2025-07-04 22:00:39.718458 :AD938ADD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:00:39.718459 :AD938ADE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=347215611 2025-07-04 22:00:39.718459 :AD938ADF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:00:39.718460 :AD938AE0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:00:39.718460 :AD938AE1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:00:39.718461 :AD938AE2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:00:39.718461 :AD938AE3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:00:39.719017 :AD938AE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:01:04.756477 :AD939437:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-04 22:01:34.859105 :AD939DC5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:01:39.821949 :AD939F96:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:01:39.810 2025-07-04 22:01:39.821951 :AD939F97:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17656 pso_num=25 pso_serial#=144 2025-07-04 22:01:39.822064 :AD939F98:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17656 osp_idx=23 osp_ver=182326 osp_pg=0 (spawn #352496) 2025-07-04 22:01:39.822064 :AD939F99:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:01:39.822065 :AD939F9A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=347275704 2025-07-04 22:01:39.822065 :AD939F9B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:01:39.822066 :AD939F9C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:01:39.822066 :AD939F9D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:01:39.822066 :AD939F9E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:01:39.822067 :AD939F9F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:01:39.822566 :AD939FA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:02:09.907931 :AD93A922:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:02:39.929487 :AD93B2D9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:02:39.918 2025-07-04 22:02:39.929489 :AD93B2DA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15788 pso_num=25 pso_serial#=145 2025-07-04 22:02:39.929633 :AD93B2DB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15788 osp_idx=23 osp_ver=182327 osp_pg=0 (spawn #352498) 2025-07-04 22:02:39.929634 :AD93B2DC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:02:39.929635 :AD93B2DD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=347335814 2025-07-04 22:02:39.929635 :AD93B2DE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:02:39.929636 :AD93B2DF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:02:39.929636 :AD93B2E0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:02:39.929637 :AD93B2E1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:02:39.929637 :AD93B2E2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:02:39.930212 :AD93B2E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:03:09.991679 :AD93BC6F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:03:40.034000 :AD93C670:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:03:40.022 2025-07-04 22:03:40.034002 :AD93C671:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13280 pso_num=25 pso_serial#=146 2025-07-04 22:03:40.034116 :AD93C672:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13280 osp_idx=23 osp_ver=182328 osp_pg=0 (spawn #352501) 2025-07-04 22:03:40.034116 :AD93C673:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:03:40.034117 :AD93C674:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=347395923 2025-07-04 22:03:40.034117 :AD93C675:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:03:40.034118 :AD93C676:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:03:40.034118 :AD93C677:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:03:40.034118 :AD93C678:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:03:40.034119 :AD93C679:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:03:40.034610 :AD93C67A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:04:10.112041 :AD93D067:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:04:40.089116 :AD93DA31:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:04:40.078 2025-07-04 22:04:40.089118 :AD93DA32:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17140 pso_num=25 pso_serial#=147 2025-07-04 22:04:40.089257 :AD93DA33:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17140 osp_idx=23 osp_ver=182329 osp_pg=0 (spawn #352503) 2025-07-04 22:04:40.089258 :AD93DA34:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:04:40.089258 :AD93DA35:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=347455970 2025-07-04 22:04:40.089259 :AD93DA36:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:04:40.089259 :AD93DA37:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:04:40.089259 :AD93DA38:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:04:40.089260 :AD93DA39:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:04:40.089260 :AD93DA3A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:04:40.089727 :AD93DA3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:05:10.175422 :AD93E90E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:05:40.168256 :AD93F2AC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:05:40.157 2025-07-04 22:05:40.168258 :AD93F2AD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13216 pso_num=25 pso_serial#=148 2025-07-04 22:05:40.168388 :AD93F2AE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13216 osp_idx=23 osp_ver=182330 osp_pg=0 (spawn #352505) 2025-07-04 22:05:40.168389 :AD93F2AF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:05:40.168389 :AD93F2B0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=347516048 2025-07-04 22:05:40.168390 :AD93F2B1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:05:40.168390 :AD93F2B2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:05:40.168390 :AD93F2B3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:05:40.168391 :AD93F2B4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:05:40.168391 :AD93F2B5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:05:40.168878 :AD93F2B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:06:10.221537 :AD93FE27:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:06:26.984546 :AD943D28:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:06:26.981 2025-07-04 22:06:26.984548 :AD943D29:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17416 pso_num=25 pso_serial#=149 2025-07-04 22:06:26.984699 :AD943D2A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17416 osp_idx=23 osp_ver=182331 osp_pg=0 (spawn #352508) 2025-07-04 22:06:26.984700 :AD943D2B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:06:26.984701 :AD943D2C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=347562861 2025-07-04 22:06:26.984701 :AD943D2D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:06:26.984701 :AD943D2E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:06:26.984702 :AD943D2F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:06:26.984702 :AD943D30:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:06:26.984702 :AD943D31:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:06:26.985954 :AD943D32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:06:26.986001 :AD943D38:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04 22:06:27.144608 :AD943D76:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-04 22:06:28.278528 :AD943E22:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-04 22:06:28.278 2025-07-04 22:06:28.278530 :AD943E23:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17560 pso_num=25 pso_serial#=150 2025-07-04 22:06:28.278673 :AD943E24:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17560 osp_idx=23 osp_ver=182332 osp_pg=0 (spawn #352510) 2025-07-04 22:06:28.278673 :AD943E25:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-04 22:06:28.278674 :AD943E26:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=347564158 2025-07-04 22:06:28.278674 :AD943E27:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-04 22:06:28.278674 :AD943E28:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-04 22:06:28.278675 :AD943E29:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-04 22:06:28.278675 :AD943E2A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-04 22:06:28.278676 :AD943E2B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-04 22:06:28.279192 :AD943E2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-04 22:06:28.279239 :AD943E32:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 17560, M002)