RP/0/RP0/CPU0:PE1#show bgp trace | utility tail -n 150
Thu Oct 15 13:09:01.780 UTC
Oct 15 13:04:41.879 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4619  [COMMON]:1308: nbr_ptr 0x7e99eaacc980, readset 0, readQ 0, last read 47270 ms, flags2 0x16 No of upd read in non establish state 0
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4662  [NBR]:14220: vrf 0x60000003 nbr reset chain: ptr 0x7e99eaacc980, stage stage 4 [updgen thread]
Oct 15 13:04:41.879 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Nbr:172.16.5.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0
Oct 15 13:04:41.879 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A-HUBOUT (1/1)) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.0 rtset 1
Oct 15 13:04:41.879 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:3184: TBL:CUST-A-HUBOUT (1/1) free subgrp SG:1 subgrp:0x7e999c00f510
Oct 15 13:04:41.879 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 1
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659  [NBR]:2306: vrf 0x60000003 nbr reset chain: ptr 0x7e99eaacc980, stage stage 5 [io-write thread]
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659  [NBR]:617: Added neighbor 172.16.5.2 to reset event queue
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4660  [NBR]:16146: vrf 0x60000003 nbr reset chain: ptr 0x7e99eaacc980, stage stage 6 [router thread]
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4632: vrf 0x60000003 nbr reset chain: ptr 0x7e99eaacc980, stage stage 7 [scanner thread]
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4314: nbr reset finish: 172.16.5.2, flags 0x380000, flags2 0x10, reason: 24, gr 0, loc 1
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4487: nbr reset finish: afi 0, af 0x04111024, af2 0x00000010, afdr 0x2, cap 0x801, ptr 0x7e99eaacc980
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4509: nbr reset finish: prev_state 6, schedule 1, flags 0x00380000, flags2 0x00000010, gr enabled 0, ptr 0x7e99eaacc980
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4523: nbr reset finish: 172.16.5.2, flags 0x380000, flags2 0x10, reason: 24, gr 0, loc 2
Oct 15 13:04:41.879 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4568: nbr reset cleared RESET_IN_PROGRESS flag: VRF 'CUST-A-HUBOUT', '172.16.5.2', reason:24 nbr_state:7 reset_nbr_count:0 reset_flags:0x3fe7
Oct 15 13:04:41.879 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4665  [GEN]:4693: (VRF 'CUST-A-HUBOUT') '172.16.5.2' reset due to 'Local AS configuration changed'
Oct 15 13:04:42.078 bgp/bpm-tr2-apic 0/RP0/CPU0 t4607  [AIPC]:2504: func bpm_ipc_cb: type, event send-status, p1 10 p2 0 p3 0
Oct 15 13:04:42.078 bgp/bpm-tr2-apic 0/RP0/CPU0 t4607  [AIPC]:525: bpm send status, role 0 (1:S, 0:A)inst-id 0, msg-id 3, send-ok 1, rel buff-seq 0
Oct 15 13:04:43.878 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:7958: trying to find update group for nbr 0x7e99eaacc980, afi All
Oct 15 13:04:43.878 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:6921: created update group for table TBL:CUST-A-HUBOUT (1/1), index 0.1, nbr 172.16.5.2, flags 0x4000
Oct 15 13:04:43.878 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:6967: Delete update group for table TBL:CUST-A-HUBOUT (1/1), index 0.2
Oct 15 13:04:43.878 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:7104: added vrf 0x60000003 nbr 172.16.5.2 to update group 0.1, afi IPv4 Unicast, flags 0x4000
Oct 15 13:04:43.880 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4006: nbr reset treewalk: batched 1 nbrs (cumulative 1)
Oct 15 13:04:43.880 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4084: nbr reset scoped walk done: tbl afi:0, rd: 0x7e99e9147e88, tbl: 0x7e99ea187de0
Oct 15 13:04:43.880 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:4124: nbr reset treewalk: end
Oct 15 13:04:43.880 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:3656: prefixes from nbr 172.16.5.2, purged 2, marked gr stale 0, afi: IPv4 Unicast
Oct 15 13:04:43.880 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4665  [NBR]:3773: nbr reset finish: 172.16.5.2, flags 0x380200, flags2 0x2000, reason: 24, gr 0, loc 3
Oct 15 13:04:43.880 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4665  [GEN]:634: vrf 0x60000003 nbr 172.16.5.2, old state 7, new state 1, fd type 0, fd 0
Oct 15 13:04:43.880 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0
Oct 15 13:04:43.880 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:4366: Config release label mode for table 0xe0000003 (mode:6, 6, 6) flags:0x201, 0x0
Oct 15 13:04:43.880 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4661  [IMPORT]:6748: Import walk: import ver 23 -> 24: 0 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 15 13:04:43.880 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4661  [IMPORT]:6777: Import walk: import ver 23 -> 24: 1 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 15 13:04:43.880 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 23, min_fwdtable_version 23, import_version 24,  label_version 23, table_version 24
Oct 15 13:04:43.881 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 23 -> 24, be 0, skp 0  upd 1
Oct 15 13:04:43.881 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 23 -> 24, be 0, skp 0  upd 1
Oct 15 13:04:43.881 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 24, min_fwdtable_version 23, import_version 24,  label_version 24, table_version 24
Oct 15 13:04:43.881 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[23 - 24]: tblver=24, labelver=24
Oct 15 13:04:43.882 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000003, converged 1, send_version 2, last_recv_version 2
Oct 15 13:04:43.884 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:17459: RIB :time_rib_batch tot_pfx = 1, rib_calls = 1, total_time = 1 ms, rib_api_time = 1 ms, avg_rib_api_time = 1 ms, max_rib_api_time = 1 ms
Oct 15 13:04:43.884 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:17487: RIB(CUST-A-HUBOUT:Vv4u): ver 23 -> 24 :pfx inst/upd/skip/rem 0/0/0/1
Oct 15 13:04:43.884 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[23 - 24]: minfwdtblver=24(acked=24)
Oct 15 13:04:43.884 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:19038:   RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=0, wdr=1}, updtrigger=1
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=1(Quick-withdraw), inputtargetver=24, pureRR=0
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[23 - 24]: resumedver=0, advdisabled=0, completed=1, msgcount=1
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=1, skp=0, sup=0, bestext=0}
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=1, targetver=24: msgcount=1
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=0, wdn=1, sup=0, skp=0}
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=23: msgcount=1
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=0, wdn=1, sup=0, skp=0}
Oct 15 13:04:43.890 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4662  [EV]:11966: Notified SysMgr of availability (Nbrs converged)
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A-HUBIN (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=1(Quick-withdraw), inputtargetver=24, pureRR=0
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[23 - 24]: resumedver=0, advdisabled=0, completed=1, msgcount=0
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0}
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:04:43.890 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4662  [COMMON]:3717: vrf 0x60000002 nbr 0x7e99eaace4c0, set peer ack version to 23, afi 0, loc 4
Oct 15 13:04:43.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A-HUBIN (1/1)) subgrp:(1) UG:(2) end 24
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A-HUBIN (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=24, pureRR=0
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[23 - 24]: resumedver=0, advdisabled=0, completed=1, msgcount=0
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0}
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:04:43.910 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4662  [COMMON]:3717: vrf 0x60000002 nbr 0x7e99eaace4c0, set peer ack version to 24, afi 0, loc 4
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A-HUBIN (1/1)) subgrp:(1) UG:(2) end 24
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=24, pureRR=0
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[23 - 24]: resumedver=0, advdisabled=0, completed=1, msgcount=0
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0}
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:04:43.910 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 24
Oct 15 13:05:09.728 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4619  [GEN]:634: vrf 0x60000003 nbr 172.16.5.2, old state 1, new state 3, fd type 2, fd 0
Oct 15 13:05:09.788 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4619  [IO]:11323: (VRF 'CUST-A-HUBOUT') Nbr '172.16.5.2': Loc 3: Determined lport/fport 29804/179
Oct 15 13:05:09.788 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4619  [NBR]:11331: send open nbr: 172.16.5.2, loc 1
Oct 15 13:05:09.788 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4619  [GEN]:2651: OPEN 'to' '172.16.5.2' vrf_id 0x60000003 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/1/2
Oct 15 13:05:09.788 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4619  [GEN]:634: vrf 0x60000003 nbr 172.16.5.2, old state 3, new state 4, fd type 1, fd 1000106
Oct 15 13:05:11.797 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4658  [GEN]:1449: OPEN 'from' '172.16.5.2' vrf_id 0x60000003 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/1/2
Oct 15 13:05:11.797 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4658  [GEN]:1449: OPEN 'from' '172.16.5.2' vrf_id 0x60000003 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/2/2
Oct 15 13:05:11.797 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4658  [GEN]:1449: OPEN 'from' '172.16.5.2' vrf_id 0x60000003 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/128/2
Oct 15 13:05:11.797 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4658  [UPD]:2109: updgrp change scheduled (0:no/1:yes):0, after open processing, nbr 172.16.5.2, flags 0x8390000
Oct 15 13:05:11.798 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4658  [EV]:1379: <gshut upd_gshut>nbr 172.16.5.2 ifhandle 0x20 ifname GigabitEthernet0_0_0_2 loc 0 
Oct 15 13:05:11.798 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4658  [GEN]:634: vrf 0x60000003 nbr 172.16.5.2, old state 4, new state 5, fd type 1, fd 1000106
Oct 15 13:05:11.798 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4658  [NBR]:2714: send keepalive nbr: 172.16.5.2, loc 1
Oct 15 13:05:11.804 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4658  [GEN]:634: vrf 0x60000003 nbr 172.16.5.2, old state 5, new state 6, fd type 1, fd 1000106
Oct 15 13:05:11.804 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4658  [GEN]:646: vrf 0x60000003 nbr '172.16.5.2' established, ptr 0x7e99eaacc980
Oct 15 13:05:11.804 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4658  [EV]:2499: Inside scan_adjust_estab_outstanding for vrf 0x60000003 nbr 172.16.5.2, nbr-down 0, state 2, evt-in-nbrwait 0
Oct 15 13:05:11.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A-HUBOUT (1/1)) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.0 rtset 1
Oct 15 13:05:11.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-A-HUBOUT:Nbr:172.16.5.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.1 rtset 0
Oct 15 13:05:11.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:2818: created filtergrp 2 for vrf 0x60000003 nbr 172.16.5.2, afi 0, subgrp version 0, refresh 0
Oct 15 13:05:11.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4592: Created subgrp:1(0x7e999c001fb0) refr:0 for nbr 172.16.5.2 (vrf CUST-A-HUBOUT), afi 0  version 0
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A-HUBOUT (1/1)", parentafi=4, afi=0, updgrp=0.1, subgrp=0.1
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0xe0000003, subgrp=0.1, walktype=0(Regular), inputtargetver=24, pureRR=0
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[0 - 24]: resumedver=0, advdisabled=0, completed=1, msgcount=1
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=2, adv=1, wdn=0, skp=1, sup=0, bestext=0}
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=0, UG=1, FG=2, walktype=0, targetver=24: msgcount=1
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1}
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=0, UG=1, FG=2, walktype=0, targetver=24: msgcount=1
Oct 15 13:05:11.834 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1}
Oct 15 13:05:11.834 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4662  [NBR]:12181: table 'TBL:CUST-A-HUBOUT (1/1)' EoR sent to sub-grp 0.1 upd-grp 1
Oct 15 13:05:13.829 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4660  [NBR]:1431: TBL:CUST-A-HUBOUT (1/1), EOR rx-ed from vrf 0x60000003 nbr '172.16.5.2', afi:0, accepted prefix count:6, explicit:1
Oct 15 13:05:13.829 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4661  [IMPORT]:6748: Import walk: import ver 24 -> 30: 0 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 15 13:05:13.829 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4661  [IMPORT]:6777: Import walk: import ver 24 -> 30: 6 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 15 13:05:13.829 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0
Oct 15 13:05:13.829 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:4366: Config release label mode for table 0xe0000003 (mode:6, 6, 6) flags:0x201, 0x0
Oct 15 13:05:13.830 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 24, min_fwdtable_version 24, import_version 30,  label_version 24, table_version 30
Oct 15 13:05:13.831 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 24 -> 30, be 6, skp 0  upd 0
Oct 15 13:05:13.831 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4645  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 24 -> 30, be 0, skp 0  upd 1
Oct 15 13:05:13.831 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 30, min_fwdtable_version 24, import_version 30,  label_version 30, table_version 30
Oct 15 13:05:13.831 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[24 - 30]: tblver=30, labelver=30
Oct 15 13:05:13.832 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000003, converged 1, send_version 2, last_recv_version 2
Oct 15 13:05:13.833 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:17459: RIB :time_rib_batch tot_pfx = 6, rib_calls = 1, total_time = 1 ms, rib_api_time = 1 ms, avg_rib_api_time = 1 ms, max_rib_api_time = 1 ms
Oct 15 13:05:13.833 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:17487: RIB(CUST-A-HUBOUT:Vv4u): ver 24 -> 30 :pfx inst/upd/skip/rem 6/0/0/0
Oct 15 13:05:13.833 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[24 - 30]: minfwdtblver=30(acked=30)
Oct 15 13:05:13.833 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4651  [RIB]:19038:   RIBUPD_END2: parentafi=4, prefixstats={install=6, skip=0, mod=0, wdr=0}, updtrigger=1
Oct 15 13:05:13.861 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A-HUBIN (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1
Oct 15 13:05:13.861 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=30, pureRR=0
Oct 15 13:05:13.861 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[24 - 30]: resumedver=0, advdisabled=0, completed=1, msgcount=0
Oct 15 13:05:13.861 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0}
Oct 15 13:05:13.861 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:05:13.861 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4662  [COMMON]:3717: vrf 0x60000002 nbr 0x7e99eaace4c0, set peer ack version to 30, afi 0, loc 4
Oct 15 13:05:13.861 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A-HUBIN (1/1)) subgrp:(1) UG:(2) end 30
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=30, pureRR=0
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[24 - 30]: resumedver=0, advdisabled=0, completed=1, msgcount=4
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=6, adv=6, wdn=0, skp=0, sup=0, bestext=0}
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=30: msgcount=4
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=6, wdn=0, sup=0, skp=0}
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A-HUBOUT (1/1)", parentafi=4, afi=0, updgrp=0.1, subgrp=0.1
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13190:   UPDGEN_UPD2: table=0xe0000003, subgrp=0.1, walktype=0(Regular), inputtargetver=30, pureRR=0
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13193:   UPDGEN_UPD3: walkversion=[24 - 30]: resumedver=0, advdisabled=0, completed=1, msgcount=0
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=6, adv=0, wdn=0, skp=6, sup=0, bestext=0}
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 15 13:05:13.863 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A-HUBOUT (1/1)) subgrp:(1) UG:(1) end 30
Oct 15 13:05:13.863 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4659  [COMMON]:857: vrf 0x60000000 nbr 0x7e99eaad0000, set peer ack version to 23, afi 4, loc 2
Oct 15 13:05:13.863 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4659  [COMMON]:857: vrf 0x60000000 nbr 0x7e99eaad1b40, set peer ack version to 23, afi 4, loc 2
Oct 15 13:05:14.805 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4659  [IO]:2195: nbr_update_timer handler: Walk complete. nbrs 1
Oct 15 13:05:41.877 bgp/bpm-tr2-apic 0/RP0/CPU0 t4607  [AIPC]:584: bpm chkpt remove: inst-id 0, buff-seq 7, total count 3, delete count 3, ok 1 
Oct 15 13:05:41.877 bgp/bpm-tr2-apic 0/RP0/CPU0 t4607  [AIPC]:210: bpm txlist show:loc 3 [1:txlist-handler, 2:conn-up 3:timer-cleanup] active-mem 1, suspend-mem 0, end-list 1, remove-mem 1, elements 0
Oct 15 13:05:41.877 bgp/bpm-tr2-apic 0/RP0/CPU0 t4607  [AIPC]:913: func txlist_timer_expiry, event restart, p1 1 p2 60 p3 1
Oct 15 13:05:41.879 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:1062: Update gen release-index bit operation TBL:CUST-A-HUBOUT (1/1) fgrp idx/size 0
Oct 15 13:05:41.879 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662  [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A-HUBOUT (1/1) fgrp idx/size 1
Oct 15 13:06:12.795 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4659  [COMMON]:857: vrf 0x60000003 nbr 0x7e99eaacc980, set peer ack version to 24, afi 0, loc 2
Oct 15 13:06:12.795 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4659  [COMMON]:1000: vrf 0x60000003 nbr 0x7e99eaacc980, set peer ack version to 30, afi 0, loc 3
Oct 15 13:06:13.828 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4658  [NBR]:1431: TBL:CUST-A-HUBOUT (1/1), EOR rx-ed from vrf 0x60000003 nbr '172.16.5.2', afi:0, accepted prefix count:6, explicit:0
Oct 15 13:06:27.796 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4659  [COMMON]:857: vrf 0x60000000 nbr 0x7e99eaad0000, set peer ack version to 30, afi 4, loc 2
Oct 15 13:06:27.796 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4659  [COMMON]:857: vrf 0x60000000 nbr 0x7e99eaad1b40, set peer ack version to 30, afi 4, loc 2
Oct 15 13:06:41.878 bgp/bpm-tr2-apic 0/RP0/CPU0 t4607  [AIPC]:210: bpm txlist show:loc 3 [1:txlist-handler, 2:conn-up 3:timer-cleanup] active-mem 1, suspend-mem 0, end-list 1, remove-mem 0, elements 0