Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607060009\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:36837M/63366M, Ph+PgF:43482M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 16 Windows thread id: 3696, image: ORACLE.EXE (SMON) *** 2025-06-07 06:00:10.021 *** SESSION ID:(129.1) 2025-06-07 06:00:10.021 *** 2025-06-07 06:00:10.021 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=35962 seq_num=35963 snap_id=1 wait times: snap=1 min 53 sec, exc=1 min 53 sec, total=1 min 53 sec wait times: max=5 min 0 sec, heur=1 min 53 sec wait counts: calls=1 os=38 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000321 sec since current wait 0: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=35961 seq_num=35962 snap_id=1 wait times: snap=0.001095 sec, exc=0.001095 sec, total=0.001095 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501f70 wait_id=35960 seq_num=35961 snap_id=1 wait times: snap=0.001656 sec, exc=0.001656 sec, total=0.001656 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000216 sec of elapsed time 2: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=35959 seq_num=35960 snap_id=1 wait times: snap=0.001096 sec, exc=0.001096 sec, total=0.001096 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 3: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501de0 wait_id=35958 seq_num=35959 snap_id=1 wait times: snap=0.001561 sec, exc=0.001561 sec, total=0.001561 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000125 sec of elapsed time 4: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=35957 seq_num=35958 snap_id=1 wait times: snap=0.001445 sec, exc=0.001445 sec, total=0.001445 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000079 sec of elapsed time 5: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501c50 wait_id=35956 seq_num=35957 snap_id=1 wait times: snap=0.001684 sec, exc=0.001684 sec, total=0.001684 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000083 sec of elapsed time 6: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=35955 seq_num=35956 snap_id=1 wait times: snap=0.001279 sec, exc=0.001279 sec, total=0.001279 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 7: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501ac0 wait_id=35954 seq_num=35955 snap_id=1 wait times: snap=0.001461 sec, exc=0.001461 sec, total=0.001461 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000083 sec of elapsed time 8: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=35953 seq_num=35954 snap_id=1 wait times: snap=0.001220 sec, exc=0.001220 sec, total=0.001220 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 9: waited for 'reliable message' channel context=0x7dc56ad40, channel handle=0x7ac4d5b90, broadcast message=0x7ac501930 wait_id=35952 seq_num=35953 snap_id=1 wait times: snap=0.001480 sec, exc=0.001480 sec, total=0.001480 sec wait times: max=1.000000 sec wait counts: calls=1 os=1 occurred after 0.000097 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, 05:58:10 - 06:00:10] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 06:00:10.021 ------------------------------------------------------------------------------- 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-06-07 05:58:16.251690 :A163776E: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-06-07 05:58:16.251751 :A163777A:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 31 2025-06-07 05:58:16.251777 :A163777B: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-06-07 05:58:16.253257 :A163778B: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-06-07 05:58:16.253267 :A163778F:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12584832 128) 2025-06-07 05:58:16.253272 :A1637790: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-06-07 05:58:16.254539 :A163779B: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-06-07 05:58:16.254597 :A16377A7:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 30 2025-06-07 05:58:16.254619 :A16377A8: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-06-07 05:58:16.256114 :A16377B9: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-06-07 05:58:16.256125 :A16377BC:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12584960 128) 2025-06-07 05:58:16.256129 :A16377BD: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-06-07 05:58:16.257365 :A16377C9: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-06-07 05:58:16.257423 :A16377D5:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 29 2025-06-07 05:58:16.257453 :A16377D6: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-06-07 05:58:16.259007 :A16377E6: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-06-07 05:58:16.259019 :A16377EA:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585088 128) 2025-06-07 05:58:16.259024 :A16377EB: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-06-07 05:58:16.260343 :A16377F8: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-06-07 05:58:16.260414 :A1637803:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 28 2025-06-07 05:58:16.260438 :A1637804: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-06-07 05:58:16.261929 :A1637814: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-06-07 05:58:16.261940 :A1637818:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585216 128) 2025-06-07 05:58:16.261945 :A1637819: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-06-07 05:58:16.263240 :A1637825: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-06-07 05:58:16.263297 :A1637831:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 27 2025-06-07 05:58:16.263320 :A1637832: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-06-07 05:58:16.264838 :A1637840: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-06-07 05:58:16.264850 :A1637846:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585344 128) 2025-06-07 05:58:16.264856 :A1637847: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-06-07 05:58:16.266105 :A1637853: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-06-07 05:58:16.266165 :A163785F:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 26 2025-06-07 05:58:16.266191 :A1637860: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-06-07 05:58:16.267722 :A1637872: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-06-07 05:58:16.267735 :A1637874:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585472 128) 2025-06-07 05:58:16.267740 :A1637875: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-06-07 05:58:16.268991 :A1637881: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-06-07 05:58:16.269049 :A163788D:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 25 2025-06-07 05:58:16.269076 :A163788E: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-06-07 05:58:16.269203 :A1637891: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-06-07 05:58:16.270746 :A16378A6: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-06-07 05:58:16.270756 :A16378AA:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585600 128) 2025-06-07 05:58:16.270761 :A16378AB: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-06-07 05:58:16.272019 :A16378B7: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-06-07 05:58:16.272076 :A16378C3:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 24 2025-06-07 05:58:16.272098 :A16378C4: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-06-07 05:58:16.273586 :A16378D3: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-06-07 05:58:16.273596 :A16378D8:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585728 128) 2025-06-07 05:58:16.273601 :A16378D9: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-06-07 05:58:16.274916 :A16378E5: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-06-07 05:58:16.274976 :A16378F1:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 23 2025-06-07 05:58:16.275000 :A16378F2: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-06-07 05:58:16.276594 :A1637905: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-06-07 05:58:16.276606 :A1637906:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585856 128) 2025-06-07 05:58:16.276611 :A1637907: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-06-07 05:58:16.277945 :A1637912: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-06-07 05:58:16.278005 :A163791F:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 22 2025-06-07 05:58:16.278028 :A1637920: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-06-07 05:58:16.279524 :A1637931: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-06-07 05:58:16.279536 :A1637934:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12585984 128) 2025-06-07 05:58:16.279540 :A1637935: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-06-07 05:58:16.280849 :A1637941: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-06-07 05:58:16.280910 :A163794D:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 21 2025-06-07 05:58:16.280937 :A163794E: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-06-07 05:58:16.282481 :A1637961: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-06-07 05:58:16.282495 :A1637962:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586112 128) 2025-06-07 05:58:16.282501 :A1637963: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-06-07 05:58:16.283711 :A163796F: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-06-07 05:58:16.283770 :A163797B:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 20 2025-06-07 05:58:16.283794 :A163797C: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-06-07 05:58:16.285340 :A163798C: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-06-07 05:58:16.285352 :A1637990:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586240 128) 2025-06-07 05:58:16.285358 :A1637991: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-06-07 05:58:16.286616 :A163799D: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-06-07 05:58:16.286677 :A16379A9:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 19 2025-06-07 05:58:16.286699 :A16379AA: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-06-07 05:58:16.288182 :A16379B6: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-06-07 05:58:16.288193 :A16379BE:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586368 128) 2025-06-07 05:58:16.288198 :A16379BF: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-06-07 05:58:16.289504 :A16379CB: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-06-07 05:58:16.289564 :A16379D7:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 18 2025-06-07 05:58:16.289586 :A16379D8: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-06-07 05:58:16.291108 :A16379E9: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-06-07 05:58:16.291118 :A16379EC:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586496 128) 2025-06-07 05:58:16.291123 :A16379ED: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-06-07 05:58:16.292346 :A16379F9: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-06-07 05:58:16.292404 :A1637A05:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 17 2025-06-07 05:58:16.292425 :A1637A06: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-06-07 05:58:16.294007 :A1637A17: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-06-07 05:58:16.294018 :A1637A1A:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586624 128) 2025-06-07 05:58:16.294023 :A1637A1B: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-06-07 05:58:16.295169 :A1637A27: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-06-07 05:58:16.295227 :A1637A33:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 16 2025-06-07 05:58:16.295248 :A1637A34: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-06-07 05:58:16.296691 :A1637A46: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-06-07 05:58:16.296700 :A1637A48:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586752 128) 2025-06-07 05:58:16.296704 :A1637A49: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-06-07 05:58:16.297651 :A1637A55: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-06-07 05:58:16.297707 :A1637A61:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 15 2025-06-07 05:58:16.297731 :A1637A62: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-06-07 05:58:16.297849 :A1637A65: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-06-07 05:58:16.299389 :A1637A7B: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-06-07 05:58:16.299399 :A1637A7E:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12586880 128) 2025-06-07 05:58:16.299403 :A1637A7F: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-06-07 05:58:16.300620 :A1637A8B: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-06-07 05:58:16.300683 :A1637A97:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 14 2025-06-07 05:58:16.300705 :A1637A98: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-06-07 05:58:16.302238 :A1637AAA: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-06-07 05:58:16.302251 :A1637AAC:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587008 128) 2025-06-07 05:58:16.302257 :A1637AAD: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-06-07 05:58:16.303493 :A1637AB9: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-06-07 05:58:16.303549 :A1637AC5:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 13 2025-06-07 05:58:16.303571 :A1637AC6: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-06-07 05:58:16.305029 :A1637AD6: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-06-07 05:58:16.305039 :A1637ADA:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587136 128) 2025-06-07 05:58:16.305043 :A1637ADB: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-06-07 05:58:16.306330 :A1637AE7: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-06-07 05:58:16.306388 :A1637AF3:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 12 2025-06-07 05:58:16.306411 :A1637AF4: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-06-07 05:58:16.307854 :A1637B06: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-06-07 05:58:16.307863 :A1637B08:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587264 128) 2025-06-07 05:58:16.307868 :A1637B09: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-06-07 05:58:16.308948 :A1637B14: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-06-07 05:58:16.309003 :A1637B20:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 11 2025-06-07 05:58:16.309023 :A1637B21: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-06-07 05:58:16.310517 :A1637B32: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-06-07 05:58:16.310527 :A1637B35:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587392 128) 2025-06-07 05:58:16.310532 :A1637B36: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-06-07 05:58:16.311751 :A1637B41: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-06-07 05:58:16.311809 :A1637B4D:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 10 2025-06-07 05:58:16.311830 :A1637B4E: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-06-07 05:58:16.313291 :A1637B5F: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-06-07 05:58:16.313301 :A1637B62:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587520 128) 2025-06-07 05:58:16.313307 :A1637B63: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-06-07 05:58:16.314343 :A1637B6E: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-06-07 05:58:16.314407 :A1637B7B:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 9 2025-06-07 05:58:16.314437 :A1637B7C: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-06-07 05:58:16.315921 :A1637B8D: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-06-07 05:58:16.315931 :A1637B90:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587648 128) 2025-06-07 05:58:16.315935 :A1637B91: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-06-07 05:58:16.317158 :A1637B9D: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-06-07 05:58:16.317217 :A1637BA9:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 8 2025-06-07 05:58:16.317238 :A1637BAA: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-06-07 05:58:16.318701 :A1637BB8: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-06-07 05:58:16.318710 :A1637BBE:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587776 128) 2025-06-07 05:58:16.318715 :A1637BBF: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-06-07 05:58:16.319995 :A1637BCB: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-06-07 05:58:16.320055 :A1637BD7:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 7 2025-06-07 05:58:16.320076 :A1637BD8: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-06-07 05:58:16.321759 :A1637BEB: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-06-07 05:58:16.321816 :A1637BEC:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12587904 128) 2025-06-07 05:58:16.321835 :A1637BED: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-06-07 05:58:16.323286 :A1637BF8: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-06-07 05:58:16.323371 :A1637C05:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 6 2025-06-07 05:58:16.323409 :A1637C06: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-06-07 05:58:16.324972 :A1637C17: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-06-07 05:58:16.324983 :A1637C1A:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588032 128) 2025-06-07 05:58:16.324989 :A1637C1B: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-06-07 05:58:16.326088 :A1637C27: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-06-07 05:58:16.326148 :A1637C33:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 5 2025-06-07 05:58:16.326175 :A1637C34: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-06-07 05:58:16.326301 :A1637C37: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-06-07 05:58:16.327959 :A1637C4E: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-06-07 05:58:16.327973 :A1637C50:db_trace:ksb.c@6610:ksbcic_int(): [10254:16:129] KSBCIC: (SMON) starting cic on opcode KSBCIRCO [1] with args (2 12588160 128) 2025-06-07 05:58:16.327978 :A1637C51: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-06-07 05:58:16.329075 :A1637C5D: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-06-07 05:58:16.329135 :A1637C69:db_trace:ktu.c@13780:ktusrs(): [10442:16:129] Shrink usn 9, free 2, check 1, nex 4 2025-06-07 05:58:16.329162 :A1637C6A: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)