RP/0/RP0/CPU0:PE2#show bgp trace | utility tail -n 150 Sun Oct 4 06:09:09.684 UTC Oct 4 05:59:52.703 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 4 05:59:52.703 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=33, pureRR=0 Oct 4 05:59:52.703 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[32 - 33]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:59:52.703 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Oct 4 05:59:52.703 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:59:52.703 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 33 Oct 4 05:59:52.704 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4681 [NBR]:4006: nbr reset treewalk: batched 1 nbrs (cumulative 1) Oct 4 05:59:52.704 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4681 [NBR]:4084: nbr reset scoped walk done: tbl afi:0, rd: 0x7631c2939f10, tbl: 0x7631c38dccc8 Oct 4 05:59:52.704 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4681 [NBR]:4124: nbr reset treewalk: end Oct 4 05:59:52.704 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4681 [NBR]:3656: prefixes from nbr 172.16.2.2, purged 4, marked gr stale 0, afi: IPv4 Unicast Oct 4 05:59:52.704 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4681 [NBR]:3773: nbr reset finish: 172.16.2.2, flags 0x304200, flags2 0x2000, reason: 10, gr 0, loc 3 Oct 4 05:59:52.704 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4681 [GEN]:634: vrf 0x60000002 nbr 172.16.2.2, old state 7, new state 1, fd type 0, fd 0 Oct 4 05:59:52.704 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:59:52.704 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 33 -> 36: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:59:52.704 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 33 -> 36: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=1(Quick-withdraw), inputtargetver=36, pureRR=0 Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[33 - 36]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=1, skp=0, sup=0, bestext=0} Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=1, targetver=36: msgcount=1 Oct 4 05:59:52.714 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=0, wdn=1, sup=0, skp=0} Oct 4 05:59:52.775 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 33, min_fwdtable_version 33, import_version 36, label_version 33, table_version 36 Oct 4 05:59:52.775 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4680 [NH]:8340: nexthop walk for AFI:'VPNv4 Unicast' start Oct 4 05:59:52.775 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4680 [NH]:8396: nexthop walk for AFI:'VPNv4 Unicast', paths deleted: 0, recalculated bestpaths: 0, color nh trigger for 0 nets, 0 msec Oct 4 05:59:52.775 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 33 -> 35, be 0, skp 0 upd 2 Oct 4 05:59:52.775 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 33 -> 35, be 0, skp 0 upd 1 Oct 4 05:59:52.775 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1109: BGP MPLS TE tunnel state reset walker - Start Oct 4 05:59:52.775 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1115: BGP MPLS TE tunnel state reset walker - End Oct 4 05:59:52.775 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:59:52.775 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 35 -> 36, be 0, skp 0 upd 0 Oct 4 05:59:52.775 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 35 -> 36, be 0, skp 0 upd 1 Oct 4 05:59:52.775 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 36, min_fwdtable_version 33, import_version 36, label_version 36, table_version 36 Oct 4 05:59:52.775 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[33 - 36]: tblver=36, labelver=36 Oct 4 05:59:52.775 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 05:59:52.776 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, 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 4 05:59:52.776 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 33 -> 36 :pfx inst/upd/skip/rem 0/1/0/1 Oct 4 05:59:52.776 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[33 - 36]: minfwdtblver=36(acked=36) Oct 4 05:59:52.776 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=1}, updtrigger=1 Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=36, pureRR=0 Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[33 - 36]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=1, skp=0, sup=0, bestext=0} Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=36: msgcount=1 Oct 4 05:59:52.804 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=0, wdn=1, sup=0, skp=0} Oct 4 06:00:16.913 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4681 [NH]:3110: VRF CUST-A gw_afi 0 null_ep_nh 0x7631d060b2f0 op 0 Oct 4 06:00:52.603 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:1062: Update gen release-index bit operation TBL:CUST-A (1/1) fgrp idx/size 0 Oct 4 06:00:52.603 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A (1/1) fgrp idx/size 1 Oct 4 06:01:03.427 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 32, afi 4, loc 2 Oct 4 06:01:03.427 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 33, afi 4, loc 2 Oct 4 06:01:03.427 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 36, afi 4, loc 2 Oct 4 06:01:03.427 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 32, afi 4, loc 2 Oct 4 06:01:03.427 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 33, afi 4, loc 2 Oct 4 06:01:03.427 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 36, afi 4, loc 2 Oct 4 06:05:41.191 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4680 [NH]:6167: nexthop notif: VRF 0x60000002, 172.16.2.2/32 is now connected, non-local, reachable, with metric 0, admin distance 0 andifhandle 0x00000008 nh_id 0x4, af_user_bits 0x1, change:1 Oct 4 06:05:41.191 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4667 [NH]:1755: VRF CUST-A gw_afi 0 null_ep_nh 0x7631d060b2f0 op 1 Oct 4 06:05:41.191 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:11792: Rx-ed 1 redist routes, 0 firsthop notifs, 0 delete Oct 4 06:05:41.191 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 06:05:41.191 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 36 -> 37, be 0, skp 0 upd 0 Oct 4 06:05:41.191 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 36 -> 37, be 0, skp 0 upd 1 Oct 4 06:05:41.191 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 36 -> 37: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 06:05:41.191 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 36, min_fwdtable_version 36, import_version 36, label_version 37, table_version 37 Oct 4 06:05:41.191 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 36 -> 37: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 37, min_fwdtable_version 36, import_version 37, label_version 37, table_version 37 Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[36 - 37]: tblver=37, labelver=37 Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17459: RIB :time_rib_batch tot_pfx = 1, rib_calls = 1, total_time = 0 ms, rib_api_time = 0 ms, avg_rib_api_time = 0 ms, max_rib_api_time = 0 ms Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 36 -> 37 :pfx inst/upd/skip/rem 0/0/0/1 Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[36 - 37]: minfwdtblver=37(acked=37) Oct 4 06:05:41.192 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=0, wdr=1}, updtrigger=1 Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=37, pureRR=0 Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[36 - 37]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=37: msgcount=1 Oct 4 06:05:41.219 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Oct 4 06:05:41.275 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4680 [NH]:8340: nexthop walk for AFI:'VPNv4 Unicast' start Oct 4 06:05:41.275 default-bgp/spkr-tr2-nh 0/RP0/CPU0 t4680 [NH]:8396: nexthop walk for AFI:'VPNv4 Unicast', paths deleted: 0, recalculated bestpaths: 0, color nh trigger for 0 nets, 0 msec Oct 4 06:05:41.275 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1109: BGP MPLS TE tunnel state reset walker - Start Oct 4 06:05:41.275 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1115: BGP MPLS TE tunnel state reset walker - End Oct 4 06:05:46.901 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4602 [GEN]:634: vrf 0x60000002 nbr 172.16.2.2, old state 1, new state 3, fd type 2, fd 0 Oct 4 06:05:48.827 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4602 [IO]:11323: (VRF 'CUST-A') Nbr '172.16.2.2': Loc 3: Determined lport/fport 35471/179 Oct 4 06:05:48.827 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4602 [NBR]:11331: send open nbr: 172.16.2.2, loc 1 Oct 4 06:05:48.827 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4602 [GEN]:2651: OPEN 'to' '172.16.2.2' vrf_id 0x60000002 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/1/2 Oct 4 06:05:48.827 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4602 [GEN]:634: vrf 0x60000002 nbr 172.16.2.2, old state 3, new state 4, fd type 1, fd 1000305 Oct 4 06:05:50.868 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4674 [GEN]:1449: OPEN 'from' '172.16.2.2' vrf_id 0x60000002 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/1/2 Oct 4 06:05:50.868 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4674 [GEN]:1449: OPEN 'from' '172.16.2.2' vrf_id 0x60000002 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/2/2 Oct 4 06:05:50.868 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4674 [GEN]:1449: OPEN 'from' '172.16.2.2' vrf_id 0x60000002 has EXTENDED NEXTHOP ENCODING cap for afi/safi/nexthop_afi: 1/128/2 Oct 4 06:05:50.868 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4674 [UPD]:2109: updgrp change scheduled (0:no/1:yes):0, after open processing, nbr 172.16.2.2, flags 0x8314000 Oct 4 06:05:50.868 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4674 [EV]:1379: nbr 172.16.2.2 ifhandle 0x8 ifname GigabitEthernet0_0_0_0 loc 0 Oct 4 06:05:50.868 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4674 [GEN]:634: vrf 0x60000002 nbr 172.16.2.2, old state 4, new state 5, fd type 1, fd 1000305 Oct 4 06:05:50.868 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4674 [NBR]:2714: send keepalive nbr: 172.16.2.2, loc 1 Oct 4 06:05:50.868 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4674 [GEN]:634: vrf 0x60000002 nbr 172.16.2.2, old state 5, new state 6, fd type 1, fd 1000305 Oct 4 06:05:50.868 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4674 [GEN]:646: vrf 0x60000002 nbr '172.16.2.2' established, ptr 0x7631d02b64c0 Oct 4 06:05:50.868 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4674 [EV]:2499: Inside scan_adjust_estab_outstanding for vrf 0x60000002 nbr 172.16.2.2, nbr-down 0, state 2, evt-in-nbrwait 0 Oct 4 06:05:50.869 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.0 rtset 2 Oct 4 06:05:50.869 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-A:Nbr:172.16.2.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 4 06:05:50.869 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:2818: created filtergrp 1 for vrf 0x60000002 nbr 172.16.2.2, afi 0, subgrp version 0, refresh 0 Oct 4 06:05:50.869 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4592: Created subgrp:1(0x76317800b6b0) refr:0 for nbr 172.16.2.2 (vrf CUST-A), afi 0 version 0 Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=37, pureRR=0 Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 37]: resumedver=0, advdisabled=0, completed=1, msgcount=3 Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=6, adv=4, wdn=0, skp=2, sup=0, bestext=0} Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=37: msgcount=3 Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=4, wdn=0, sup=0, skp=2} Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=37: msgcount=1 Oct 4 06:05:50.898 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=4, wdn=0, sup=0, skp=2} Oct 4 06:05:50.898 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4678 [NBR]:12181: table 'TBL:CUST-A (1/1)' EoR sent to sub-grp 0.1 upd-grp 2 Oct 4 06:05:50.966 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4676 [NBR]:1431: TBL:CUST-A (1/1), EOR rx-ed from vrf 0x60000002 nbr '172.16.2.2', afi:0, accepted prefix count:2, explicit:1 Oct 4 06:05:50.966 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 06:05:50.966 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 37 -> 39: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 06:05:50.967 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 37 -> 39: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 06:05:50.967 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 37, min_fwdtable_version 37, import_version 39, label_version 37, table_version 39 Oct 4 06:05:50.967 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 37 -> 39, be 2, skp 0 upd 0 Oct 4 06:05:50.967 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 37 -> 39, be 0, skp 0 upd 1 Oct 4 06:05:50.967 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 39, min_fwdtable_version 37, import_version 39, label_version 39, table_version 39 Oct 4 06:05:50.967 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[37 - 39]: tblver=39, labelver=39 Oct 4 06:05:50.968 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 06:05:50.969 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, 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 4 06:05:50.969 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 37 -> 39 :pfx inst/upd/skip/rem 1/1/0/0 Oct 4 06:05:50.969 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[37 - 39]: minfwdtblver=39(acked=39) Oct 4 06:05:50.969 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=1, wdr=0}, updtrigger=1 Oct 4 06:05:50.995 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 4 06:05:50.995 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=39, pureRR=0 Oct 4 06:05:50.995 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[37 - 39]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 06:05:50.995 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 4 06:05:50.995 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 06:05:50.995 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 39 Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=39, pureRR=0 Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[37 - 39]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=2, wdn=0, skp=0, sup=0, bestext=0} Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=39: msgcount=1 Oct 4 06:05:50.998 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=0} Oct 4 06:05:50.998 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 37, afi 4, loc 2 Oct 4 06:05:50.998 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 37, afi 4, loc 2 Oct 4 06:05:51.870 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1027: IDB: rx-ed IMC bulk notif, 1 events Oct 4 06:05:51.870 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1067: IDB: msg opcode type: create, ifhhandle: 0x8, state: unknown Oct 4 06:05:51.870 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1027: IDB: rx-ed IMC bulk notif, 1 events Oct 4 06:05:51.870 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1040: IDB: msg opcode type: state value, ifhhandle: 0x8, state: up Oct 4 06:05:51.871 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4680 [EV]:1027: IDB: rx-ed IMC bulk notif, 1 events Oct 4 06:06:50.977 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4674 [NBR]:1431: TBL:CUST-A (1/1), EOR rx-ed from vrf 0x60000002 nbr '172.16.2.2', afi:0, accepted prefix count:2, explicit:0 Oct 4 06:07:03.479 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 39, afi 4, loc 2 Oct 4 06:07:03.479 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 39, afi 4, loc 2 Oct 4 06:07:03.479 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 37, afi 0, loc 2 Oct 4 06:07:03.479 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 39, afi 0, loc 3