RP/0/RP0/CPU0:PE2#show bgp trace | utility tail -n 150 Mon Oct 12 00:26:33.361 UTC Oct 12 00:22:45.603 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4695 [EV]:7099: Try to enter 'Label Allocation' mode for AF 'VPNv4 Unicast', thread 4 Oct 12 00:22:45.603 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4695 [EV]:9672: Entering 'Label Allocation' mode for AF 'VPNv4 Unicast' Oct 12 00:22:45.603 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6748: Import walk: import ver 7 -> 9: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.603 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6777: Import walk: import ver 7 -> 9: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.603 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x1, 0x0 Oct 12 00:22:45.603 default-bgp/spkr-tr2-mvpn 0/RP0/CPU0 t4699 [MVPN]:4078: BGP end readonly: force vpn table refresh, vpn afi 4, rc 0 Oct 12 00:22:45.603 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:6244: Label RW request for table-id 0xe0000002 mode:0 Oct 12 00:22:45.604 default-bgp/spkr-tr2-mvpn 0/RP0/CPU0 t4699 [MVPN]:1556: BGP end readonly: Scan force vpn refresh, vpn afi 4, rc 0 Oct 12 00:22:45.606 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:2178: Label change on table 0xe0000002 (old label:0, new label:24006) Oct 12 00:22:45.606 default-bgp/spkr-tr2-l2vpn 0/RP0/CPU0 t4683 [L2VPN]:5109: EVPN - L3 Label trigger: afi 0, encap 1 Oct 12 00:22:45.606 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:2237: Allocated label 24006 VRF:(VRF 'TBL:CUST-A (1/1)') tbl_id:0xe0000002 flags1:0x201 flags2:0x0 mode:0 Oct 12 00:22:45.606 default-bgp/spkr-tr2-l2vpn 0/RP0/CPU0 t4702 [L2VPN]:5159: EVPN - Receive EVPN L3 Label Update: afi: 0 table_ctx: 0x7e6b96a88cc8 VRF: CUST-A Oct 12 00:22:45.606 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4683 [EV]:963: Sent rsrc-complete notif to LSD Oct 12 00:22:45.606 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4683 [EV]:969: bgp label stop rsrc-complete notif timer - reason 1 Oct 12 00:22:45.606 default-bgp/spkr-tr2-srv6 0/RP0/CPU0 t4683 [SRV6]:1859: SRv6: bgp_srv6_sid_resource_notf_timer loc: 6 resource complete notif sent, caller loc 2 Oct 12 00:22:45.606 default-bgp/spkr-tr2-srv6 0/RP0/CPU0 t4683 [SRV6]:1865: SRv6: bgp_srv6_sid_resource_notf_timer loc: 7 resource complete notif sent, caller loc 2 Oct 12 00:22:45.606 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 0 -> 9, be 2, skp 0 upd 0 Oct 12 00:22:45.606 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 0 -> 9, be 0, skp 0 upd 1 Oct 12 00:22:45.606 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4683 [EV]:9643: Try to enter 'RIB Update' mode for AF 'VPNv4 Unicast', thread 8 Oct 12 00:22:45.606 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4683 [EV]:19718: Entering 'RIB Update' mode for AF 'VPNv4 Unicast' Oct 12 00:22:45.606 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 9, min_fwdtable_version 1, import_version 9, label_version 9, table_version 11 Oct 12 00:22:45.606 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=5}, walkversion=[1 - 9]: tblver=11, labelver=9 Oct 12 00:22:45.606 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4698 [SR]:913: SR-policy XTC got EoD notif from XTC: 1 Oct 12 00:22:45.607 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4698 [SR]:1713: SR-policy XTC stale NH tunnels sweeped, trigger nh walk 0 Oct 12 00:22:45.607 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4698 [SR]:1494: SR-policy null bgp_ctx - afi 23, op table_walk Oct 12 00:22:45.607 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4698 [SR]:1620: SR-policy table walk, afi 23, OP - sweep-stale done, updated 0 nets, 0 locally sourced paths Oct 12 00:22:45.607 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4698 [SR]:1494: SR-policy null bgp_ctx - afi 24, op table_walk Oct 12 00:22:45.607 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4698 [SR]:1633: SR-policy table walk, afi 24, OP - sweep-stale done, updated 0 nets, 0 locally sourced paths Oct 12 00:22:45.607 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 00:22:45.608 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:16250: RIB converge flags (VRF 'CUST-A') , 0x5, 1, afi 0 tr_upd_gen:0 Oct 12 00:22:45.608 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:17459: RIB :time_rib_batch tot_pfx = 4, rib_calls = 1, total_time = 1 ms, rib_api_time = 0 ms, avg_rib_api_time = 0 ms, max_rib_api_time = 0 ms Oct 12 00:22:45.608 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:17487: RIB(CUST-A:Vv4u): ver 1 -> 9 :pfx inst/upd/skip/rem 3/0/0/1 Oct 12 00:22:45.608 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=5}, walkversion=[1 - 9]: minfwdtblver=9(acked=9) Oct 12 00:22:45.608 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=3, skip=0, mod=0, wdr=1}, updtrigger=1 Oct 12 00:22:45.608 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4686 [EV]:19593: Entering 'Normal' mode for AF 'VPNv4 Unicast' Oct 12 00:22:45.608 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4686 [SYNC]:19622: entering READ-WRITE mode, set initsync version for bgp_ctx 'VPNv4 Unicast' to 9 Oct 12 00:22:45.610 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:6257: RIB converge flags (VRF 'CUST-A') , 0x7, 0, afi 0 tr_upd_gen:1 Oct 12 00:22:45.610 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:6263: RIB converge flags (VRF 'CUST-A') , 0x3, 0, afi 0 tr_upd_gen:1 Oct 12 00:22:45.610 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:6296: ACK from RIB: tbl_id 0xe0000002, converged 1, ver 2, pending nhscan 0 Oct 12 00:22:45.610 default-bgp/spkr-tr2-table 0/RP0/CPU0 t4698 [TABLE]:6322: table 'TBL:CUST-A (1/1)' propogate rd version, bgp tbl ver 12 Oct 12 00:22:45.610 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6748: Import walk: import ver 9 -> 12: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.610 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6777: Import walk: import ver 9 -> 12: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.610 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 00:22:45.610 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 9 -> 12, be 0, skp 0 upd 0 Oct 12 00:22:45.610 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 9 -> 12, be 0, skp 0 upd 1 Oct 12 00:22:45.614 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 12, min_fwdtable_version 9, import_version 12, label_version 12, table_version 12 Oct 12 00:22:45.614 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[9 - 12]: tblver=12, labelver=12 Oct 12 00:22:45.614 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 00:22:45.615 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, 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 12 00:22:45.615 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:17487: RIB(CUST-A:Vv4u): ver 9 -> 12 :pfx inst/upd/skip/rem 0/1/0/1 Oct 12 00:22:45.615 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[9 - 12]: minfwdtblver=12(acked=12) Oct 12 00:22:45.615 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=1}, updtrigger=1 Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=12, pureRR=0 Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 12]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=4, adv=2, wdn=0, skp=2, sup=0, bestext=0} Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=12: msgcount=2 Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=2} Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=12: msgcount=1 Oct 12 00:22:45.638 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=2} Oct 12 00:22:45.638 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4696 [NBR]:12181: table 'TBL:default (1/128)' EoR sent to sub-grp 0.1 upd-grp 2 Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=12, pureRR=0 Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 12]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=4, adv=2, wdn=0, skp=2, sup=0, bestext=0} Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=12: msgcount=2 Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=2} Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=12: msgcount=1 Oct 12 00:22:45.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=2} Oct 12 00:22:45.648 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4696 [NBR]:12181: table 'TBL:CUST-A (1/1)' EoR sent to sub-grp 0.1 upd-grp 2 Oct 12 00:22:45.655 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4694 [NBR]:1431: TBL:default (1/128), EOR rx-ed from vrf 0x60000000 nbr '4.4.4.4', afi:4, accepted prefix count:2, explicit:1 Oct 12 00:22:45.655 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6748: Import walk: import ver 12 -> 14: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6777: Import walk: import ver 12 -> 14: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 12 -> 15, be 0, skp 0 upd 0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 12 -> 15, be 0, skp 0 upd 1 Oct 12 00:22:45.656 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6748: Import walk: import ver 14 -> 16: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4695 [IMPORT]:6777: Import walk: import ver 14 -> 16: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 15 -> 16, be 0, skp 0 upd 0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4683 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 15 -> 16, be 0, skp 0 upd 1 Oct 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 16, min_fwdtable_version 12, import_version 16, label_version 16, table_version 16 Oct 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[12 - 16]: tblver=16, labelver=16 Oct 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, 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 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:17487: RIB(CUST-A:Vv4u): ver 12 -> 16 :pfx inst/upd/skip/rem 2/0/0/0 Oct 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[12 - 16]: minfwdtblver=16(acked=16) Oct 12 00:22:45.656 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4686 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=2, skip=0, mod=0, wdr=0}, updtrigger=1 Oct 12 00:22:45.682 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 00:22:45.682 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=16, pureRR=0 Oct 12 00:22:45.682 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13193: UPDGEN_UPD3: walkversion=[12 - 16]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 00:22:45.682 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 12 00:22:45.682 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:22:45.682 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 16 Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=16, pureRR=0 Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13193: UPDGEN_UPD3: walkversion=[12 - 16]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=1, wdn=0, skp=1, sup=0, bestext=0} Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=16: msgcount=1 Oct 12 00:22:45.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1} Oct 12 00:22:45.685 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=16: msgcount=1 Oct 12 00:22:45.685 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1} Oct 12 00:23:11.853 bgp/bpm-tr2-apic 0/RP0/CPU0 t4612 [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 Oct 12 00:23:33.815 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4625 [POSTIT]:5163: skip postit: 'Outbound user soft clear', nbr: 172.16.2.2, reason: 1 Oct 12 00:23:33.815 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4699 [POSTIT]:5163: skip postit: 'None', nbr: 172.16.2.2, reason: 1 Oct 12 00:23:33.815 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:957: Updgen - Resending table to neighbor 172.16.2.2, afi IPv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Oct 12 00:23:35.577 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4699 [COMMON]:2504: nbr soft-reconfig 'ptr: 0x7e6b974734c0',afi: 0 af_flag: 0x011100a4 af3_flag: 0x00080000 loc: 1 Oct 12 00:23:35.577 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4699 [COMMON]:2571: soft-reconfig delayed bestpath: elapsed time(ms) 0, total run 0 , afi IPv4 Unicast, count 1 Oct 12 00:23:35.816 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4699 [POSTIT]:7502: skip pending postit: nbr: 172.16.2.2, afi: 0, global nsr not ready Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Nbr:172.16.2.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.0 rtset 2 Oct 12 00:23:35.816 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4699 [COMMON]:6095: nbr_ptr 0x7e6b974734c0 resend updates, afi IPv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.2.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.0 rtset 2 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [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.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:2818: created filtergrp 2 for vrf 0x60000002 nbr 172.16.2.2, afi 0, subgrp version 0, refresh 0 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4699 [UPD]:3392: TBL:CUST-A (1/1) delete subgrp UG/SG:2/1 subgrp:0x7e6b4400b6b0 caller 0x636fcccf7e0f Oct 12 00:23:35.816 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4696 [NBR]:5297: bgp_default_originate table:0x7e6b96a88cc8 nbr:0x7e6b974734c0 afi:IPv4 Unicast def_vpn_ctx:REFRESH action:DONOTHING bgp_default_orig_ctx:REFRESH bgp_default_orig_state:Zero old_bgp_default_orig_state:Zero msg-created:False loc:00066700 Oct 12 00:23:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 1 Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.2 Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.2, walktype=0(Regular), inputtargetver=16, pureRR=0 Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 16]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=6, adv=3, wdn=0, skp=3, sup=0, bestext=0} Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=2, walktype=0, targetver=16: msgcount=2 Oct 12 00:23:35.847 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=3, wdn=0, sup=0, skp=3} Oct 12 00:23:35.847 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:857: vrf 0x60000002 nbr 0x7e6b974734c0, set peer ack version to 12, afi 0, loc 2 Oct 12 00:23:35.847 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:857: vrf 0x60000002 nbr 0x7e6b974734c0, set peer ack version to 16, afi 0, loc 2 Oct 12 00:23:57.873 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:857: vrf 0x60000000 nbr 0x7e6b97476b40, set peer ack version to 12, afi 4, loc 2 Oct 12 00:23:57.873 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:1000: vrf 0x60000000 nbr 0x7e6b97476b40, set peer ack version to 16, afi 4, loc 3 Oct 12 00:23:57.873 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:857: vrf 0x60000000 nbr 0x7e6b97475000, set peer ack version to 12, afi 4, loc 2 Oct 12 00:23:57.873 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:1000: vrf 0x60000000 nbr 0x7e6b97475000, set peer ack version to 16, afi 4, loc 3 Oct 12 00:24:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:1 subgrp:0x7e6b4400b6b0 Oct 12 00:24:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:1062: Update gen release-index bit operation TBL:CUST-A (1/1) fgrp idx/size 0 Oct 12 00:24:35.816 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4696 [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A (1/1) fgrp idx/size 1 Oct 12 00:24:42.874 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4693 [COMMON]:857: vrf 0x60000002 nbr 0x7e6b974734c0, set peer ack version to 16, afi 0, loc 2 Oct 12 00:24:48.603 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4699 [SYNC]:2280: reached initsync version 9 for afi VPNv4 Unicast, min_nbr_ack version 16, scanner_runs 3 Oct 12 00:24:48.603 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4699 [SYNC]:1792: NSR event: 'End of Convergence' on active Oct 12 00:24:48.603 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4699 [SYNC]:1792: NSR event: 'End of Convergence' on active Oct 12 00:24:48.603 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4697 [SYNC]:6368: active NSR state transition for 'Standby Sync-group', event 'End of Convergence', 'Active Ready' -> 'Active Ready', mask 0x12 Oct 12 00:24:48.604 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4697 [SYNC]:6368: active NSR state transition for 'ISSU Sync-group', event 'End of Convergence', 'None' -> 'None', mask 0x10 Oct 12 00:24:48.604 default-bgp/spkr-tr2-issu 0/RP0/CPU0 t4697 [ISSU]:541: ISM: ISSU Not enabled Oct 12 00:24:48.604 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4697 [SYNC]:6460: send active up msg to 'ISSU Sync-group' 'Unknown', after NSR restart or tcp restart Oct 12 00:24:48.604 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4697 [SYNC]:5241: scoped sync: trigger forced cleanup, batch state 0, count 0 Oct 12 00:24:48.604 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4620 [SYNC]:4950: scoped sync: forced cleanup, batch state 0 Oct 12 00:24:48.604 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4620 [SYNC]:4952: scoped sync: batch state change from 0 to 0 Oct 12 00:24:48.604 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4697 [SYNC]:8714: scoped sync: skip sending table update done, global NSR not ready