Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802100521\orcl_smon_3696_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:35828M/63366M, Ph+PgF:41490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 16 Windows thread id: 3696, image: ORACLE.EXE (SMON) *** 2025-08-02 10:05:21.253 *** SESSION ID:(129.1) 2025-08-02 10:05:21.253 *** 2025-08-02 10:05:21.253 Process diagnostic dump for ORACLE.EXE (SMON), OS id=3696, pid: 16, proc_ser: 1, sid: 129, sess_ser: 1 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'smon timer' sleep time=0x12c, failed=0x0, =0x0 wait_id=62474 seq_num=62475 snap_id=1 wait times: snap=4 min 26 sec, exc=4 min 26 sec, total=4 min 26 sec wait times: max=5 min 0 sec, heur=4 min 26 sec wait counts: calls=1 os=89 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000142 sec since current wait 0: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=62473 seq_num=62474 snap_id=1 wait times: snap=0.000097 sec, exc=0.000097 sec, total=0.000097 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501930 wait_id=62472 seq_num=62473 snap_id=1 wait times: snap=0.000228 sec, exc=0.000228 sec, total=0.000228 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000053 sec of elapsed time 2: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=62471 seq_num=62472 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac5017a0 wait_id=62470 seq_num=62471 snap_id=1 wait times: snap=0.000174 sec, exc=0.000174 sec, total=0.000174 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000043 sec of elapsed time 4: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=62469 seq_num=62470 snap_id=1 wait times: snap=0.000055 sec, exc=0.000055 sec, total=0.000055 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501610 wait_id=62468 seq_num=62469 snap_id=1 wait times: snap=0.000200 sec, exc=0.000200 sec, total=0.000200 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000043 sec of elapsed time 6: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=62467 seq_num=62468 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501480 wait_id=62466 seq_num=62467 snap_id=1 wait times: snap=0.000183 sec, exc=0.000183 sec, total=0.000183 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000057 sec of elapsed time 8: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=62465 seq_num=62466 snap_id=1 wait times: snap=0.000081 sec, exc=0.000081 sec, total=0.000081 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 9: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac502290 wait_id=62464 seq_num=62465 snap_id=1 wait times: snap=0.000277 sec, exc=0.000277 sec, total=0.000277 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000055 sec of elapsed time Sampled Session History of session 129 serial 1 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 10:03:21 - 10:05:21] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 10:05:21.253 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 16 (osid: 3696, SMON) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 09:52:54.405106 :BA46D57C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.407533 :BA46D58E:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.407551 :BA46D590:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587648 128) 2025-08-02 09:52:54.407558 :BA46D591:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.409766 :BA46D59E:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.409830 :BA46D5A9:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 21 2025-08-02 09:52:54.409856 :BA46D5AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.412378 :BA46D5B9:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.412396 :BA46D5BE:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587776 128) 2025-08-02 09:52:54.412403 :BA46D5BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.414654 :BA46D5CB:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.414720 :BA46D5D7:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 20 2025-08-02 09:52:54.414749 :BA46D5D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.417304 :BA46D5EB:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.417323 :BA46D5EC:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587904 128) 2025-08-02 09:52:54.417330 :BA46D5ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.419688 :BA46D5F9:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.419759 :BA46D605:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 19 2025-08-02 09:52:54.419786 :BA46D606:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.422509 :BA46D618:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.422528 :BA46D61A:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588032 128) 2025-08-02 09:52:54.422536 :BA46D61B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.424903 :BA46D628:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.424970 :BA46D633:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 18 2025-08-02 09:52:54.424999 :BA46D634:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.427629 :BA46D647:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.427645 :BA46D648:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588160 128) 2025-08-02 09:52:54.427653 :BA46D649:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.429995 :BA46D656:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.430064 :BA46D661:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 17 2025-08-02 09:52:54.430095 :BA46D662:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.432417 :BA46D674:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.432434 :BA46D676:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588288 128) 2025-08-02 09:52:54.432441 :BA46D677:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.434526 :BA46D684:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.434591 :BA46D68F:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 16 2025-08-02 09:52:54.434618 :BA46D690:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.436855 :BA46D69F:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.436870 :BA46D6A4:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588416 128) 2025-08-02 09:52:54.436876 :BA46D6A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.438916 :BA46D6B2:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.438977 :BA46D6BD:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 15 2025-08-02 09:52:54.439003 :BA46D6BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.439122 :BA46D6C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.440807 :BA46D6D7:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.440819 :BA46D6DA:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588544 128) 2025-08-02 09:52:54.440824 :BA46D6DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.442138 :BA46D6E7:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.442200 :BA46D6F3:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 14 2025-08-02 09:52:54.442224 :BA46D6F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.444429 :BA46D704:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.444444 :BA46D708:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588672 128) 2025-08-02 09:52:54.444450 :BA46D709:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.446350 :BA46D714:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.446412 :BA46D721:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 13 2025-08-02 09:52:54.446439 :BA46D722:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.448739 :BA46D733:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.448755 :BA46D736:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588800 128) 2025-08-02 09:52:54.448761 :BA46D737:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.450742 :BA46D743:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.450802 :BA46D74F:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 12 2025-08-02 09:52:54.450827 :BA46D750:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.453152 :BA46D762:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.453170 :BA46D763:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588928 128) 2025-08-02 09:52:54.453177 :BA46D764:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.455268 :BA46D770:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.455329 :BA46D77C:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 11 2025-08-02 09:52:54.455354 :BA46D77D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.457811 :BA46D78E:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.457828 :BA46D791:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12589056 128) 2025-08-02 09:52:54.457834 :BA46D792:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.460121 :BA46D79E:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.460185 :BA46D7AA:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 10 2025-08-02 09:52:54.460210 :BA46D7AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.462644 :BA46D7BA:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.462659 :BA46D7BF:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12589184 128) 2025-08-02 09:52:54.462665 :BA46D7C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.464935 :BA46D7CC:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.465002 :BA46D7D8:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 9 2025-08-02 09:52:54.465030 :BA46D7D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.467479 :BA46D7EC:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.467498 :BA46D7ED:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12589312 128) 2025-08-02 09:52:54.467505 :BA46D7EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.469799 :BA46D7FC:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.469879 :BA46D806:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 8 2025-08-02 09:52:54.469911 :BA46D807:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.472220 :BA46D81A:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.472238 :BA46D81B:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12589568 128) 2025-08-02 09:52:54.472246 :BA46D81C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.474402 :BA46D828:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.474466 :BA46D834:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 7 2025-08-02 09:52:54.474491 :BA46D835:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.476794 :BA46D846:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.476808 :BA46D849:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12589696 128) 2025-08-02 09:52:54.476814 :BA46D84A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.479019 :BA46D856:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.479082 :BA46D862:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 6 2025-08-02 09:52:54.479111 :BA46D863:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.481353 :BA46D874:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.481369 :BA46D877:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12589824 128) 2025-08-02 09:52:54.481374 :BA46D878:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:52:54.483179 :BA46D885:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 09:52:54.483242 :BA46D890:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 10, free 2, check 1, nex 5 2025-08-02 09:52:54.483268 :BA46D891:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 09:57:54.499055 :BA473A59:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 0 2025-08-02 09:57:54.499069 :BA473A5A:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 1 2025-08-02 09:57:54.499086 :BA473A5B:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 2 2025-08-02 09:57:54.499091 :BA473A5C:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 3 2025-08-02 09:57:54.499097 :BA473A5D:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 4 2025-08-02 09:57:54.499103 :BA473A5E:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 5 2025-08-02 09:57:54.499109 :BA473A5F:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 6 2025-08-02 09:57:54.499115 :BA473A60:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 7 2025-08-02 09:57:54.499121 :BA473A61:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 8 2025-08-02 09:57:54.499127 :BA473A62:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 9 2025-08-02 09:57:54.499131 :BA473A63:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 10 2025-08-02 09:57:54.499861 :BA473A64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.909861 :BA47782C:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=24 loc='ktm.h LINE:583 ID:ktmpsm' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.909941 :BA47782D:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 0 2025-08-02 10:00:54.909952 :BA47782E:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 1 2025-08-02 10:00:54.909959 :BA47782F:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 2 2025-08-02 10:00:54.909964 :BA477830:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 3 2025-08-02 10:00:54.909971 :BA477831:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 4 2025-08-02 10:00:54.909976 :BA477832:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 5 2025-08-02 10:00:54.909982 :BA477833:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 6 2025-08-02 10:00:54.909987 :BA477834:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 7 2025-08-02 10:00:54.910032 :BA477836:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.910036 :BA477837:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 8 2025-08-02 10:00:54.910041 :BA477838:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 9 2025-08-02 10:00:54.910046 :BA477839:db_trace:ktur.c@1630:kturRecoverUndoSegment(): [10444:16:129] UNDO SEG (BEFORE RECOVERY): usn = 10 2025-08-02 10:00:54.910166 :BA47783A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.910613 :BA477850:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.910622 :BA477855:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12583144 8) 2025-08-02 10:00:54.910628 :BA477858:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.910728 :BA477863:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.910772 :BA477880:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 9, check 1, nex 12 2025-08-02 10:00:54.910787 :BA477881:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.911134 :BA477892:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.911143 :BA477895:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12583200 8) 2025-08-02 10:00:54.911148 :BA477896:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.911241 :BA4778A6:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.911282 :BA4778AE:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 9, check 1, nex 11 2025-08-02 10:00:54.911293 :BA4778AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.911573 :BA4778BF:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.911580 :BA4778C3:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12583216 8) 2025-08-02 10:00:54.911584 :BA4778C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.911666 :BA4778D1:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.911698 :BA4778DC:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 9, check 1, nex 10 2025-08-02 10:00:54.911722 :BA4778DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.911906 :BA4778EE:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.911912 :BA4778F0:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12590104 8) 2025-08-02 10:00:54.911916 :BA4778F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.911969 :BA4778FE:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912000 :BA477909:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 2, check 1, nex 9 2025-08-02 10:00:54.912011 :BA47790A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.912212 :BA47791B:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912218 :BA47791E:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12590112 8) 2025-08-02 10:00:54.912221 :BA47791F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.912277 :BA47792C:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912308 :BA477937:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 2, check 1, nex 8 2025-08-02 10:00:54.912318 :BA477938:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.912494 :BA477943:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912500 :BA47794C:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12590128 8) 2025-08-02 10:00:54.912502 :BA47794D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.912556 :BA47795A:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912592 :BA477965:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 2, check 1, nex 7 2025-08-02 10:00:54.912606 :BA477966:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.912837 :BA477973:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912843 :BA47797A:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12590136 8) 2025-08-02 10:00:54.912846 :BA47797B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:54.912944 :BA477986:db_trace:ksl2.c@12699:ksliwat(): [10005:16:129] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 10:00:54.912972 :BA477992:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 8, free 2, check 1, nex 6 2025-08-02 10:00:54.912995 :BA477993:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:16:129] 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 16 (osid: 3696, SMON)