RP/0/RP0/CPU0:PE1#show bgp trace | utility tail -n 150
Mon Oct 12 09:56:40.863 UTC
Oct 12 09:53:14.456 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4480  [GEN]:634: vrf 0x60000000 nbr 2.2.2.2, old state 2, new state 4, fd type 1, fd 1000003
Oct 12 09:53:14.456 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4480  [GEN]:634: vrf 0x60000000 nbr 2.2.2.2, old state 4, new state 5, fd type 1, fd 1000003
Oct 12 09:53:14.456 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4480  [NBR]:2714: send keepalive nbr: 2.2.2.2, loc 1
Oct 12 09:53:14.470 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4480  [GEN]:634: vrf 0x60000000 nbr 2.2.2.2, old state 5, new state 6, fd type 1, fd 1000003
Oct 12 09:53:14.470 default-bgp/spkr-tr2-gen 0/RP0/CPU0 t4480  [GEN]:646: vrf 0x60000000 nbr '2.2.2.2' established, ptr 0x7c3a449acb40
Oct 12 09:53:14.470 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4480  [EV]:2499: Inside scan_adjust_estab_outstanding for vrf 0x60000000 nbr 2.2.2.2, nbr-down 0, state 2, evt-in-nbrwait 0
Oct 12 09:53:14.472 default-bgp/spkr-tr2-ha 0/RP0/CPU0 t4486  [HA]:6102: sent NSR not ready notif to RMF
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:7958: trying to find update group for nbr 0x7c3a449acb40, afi All
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:6921: created update group for table TBL:default (1/128), index 0.2, nbr 2.2.2.2, flags 0x104183
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:6967: Delete update group for table TBL:default (1/128), index 0.1
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:default (1/128)) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.0 rtset 2
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:VPNv4 Unicast:Vrf:default:Nbr:2.2.2.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:2818: created filtergrp 1 for vrf 0x60000000 nbr 2.2.2.2, afi 4, subgrp version 0, refresh 0
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:4592: Created subgrp:1(0x7c39ec004ba0) refr:0 for nbr 2.2.2.2 (vrf default), afi 4  version 0
Oct 12 09:53:16.457 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:7104: added vrf 0x60000000 nbr 2.2.2.2 to update group 0.2, afi VPNv4 Unicast, flags 0x104983
Oct 12 09:53:18.554 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4487  [EV]:2266: GW Cache: lsd conn event: 1
Oct 12 09:53:18.554 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:11913: bgp label handle lsd event: UP
Oct 12 09:53:18.554 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:11983: bgp label start rsrc-complete notif timer - reason 1 value 1500 sec
Oct 12 09:53:18.554 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:11294: bgp label start reclaim walk
Oct 12 09:53:46.352 bgp/bpm-tr2-apic 0/RP0/CPU0 t4402  [AIPC]:584: bpm chkpt remove: inst-id 0, buff-seq 5, total count 13, delete count 13, ok 1 
Oct 12 09:53:46.352 bgp/bpm-tr2-apic 0/RP0/CPU0 t4402  [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 12 09:53:46.352 bgp/bpm-tr2-apic 0/RP0/CPU0 t4402  [AIPC]:913: func txlist_timer_expiry, event restart, p1 1 p2 60 p3 1
Oct 12 09:54:14.476 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4480  [NBR]:1431: TBL:default (1/128), EOR rx-ed from vrf 0x60000000 nbr '2.2.2.2', afi:4, accepted prefix count:0, explicit:0
Oct 12 09:54:14.476 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4480  [EV]:2629: Trigger exit RO mode for afi VPNv4 Unicast, loc 2
Oct 12 09:54:14.476 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4488  [SYNC]:1792: NSR event: 'End of read-only' on active
Oct 12 09:54:14.476 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4488  [LABEL]:12425: 0 RPC set entries found (error: iter 0, data 0) in chkpt database for table id 0x1e88 afi: 4 success: 1
Oct 12 09:54:14.476 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4486  [SYNC]:6368: active NSR state transition for 'Standby Sync-group', event 'End of read-only', 'None' -> 'Active Ready', mask 0x2
Oct 12 09:54:14.476 default-bgp/spkr-tr2-l2vpn 0/RP0/CPU0 t4491  [L2VPN]:8653: EVPN GW Track:Recv Msg NH:,Track:0 Ifh:0x0 Conv:1
Oct 12 09:54:14.476 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4488  [LABEL]:12425: 0 RPC set entries found (error: iter 0, data 0) in chkpt database for table id 0x18a8 afi: 4 success: 1
Oct 12 09:54:14.476 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4488  [EV]:1282: Entering 'Bestpath Calculation' mode for AF 'VPNv4 Unicast'
Oct 12 09:54:14.477 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:724: SR-policy XTC send EoD notif to XTC
Oct 12 09:54:14.477 default-bgp/spkr-tr2-mvpn 0/RP0/CPU0 t4488  [MVPN]:4609: Update PIM RD msg 3 : afi 0, vrf CUST-A, rd 2ASN:65001:1, ret 0
Oct 12 09:54:14.477 default-bgp/spkr-tr2-mvpn 0/RP0/CPU0 t4488  [MVPN]:4966: Download vrf rd:vrf CUST-A, afi 4, rd 2ASN:65001:1
Oct 12 09:54:14.477 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4471  [EV]:19520: Entering 'Import' mode for AF 'VPNv4 Unicast'
Oct 12 09:54:14.477 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4484  [EV]:7147: Import mode handler for af 'VPNv4 Unicast', loc 0, tablever 9 import ver 0 
Oct 12 09:54:14.477 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6748: Import walk: import ver 0 -> 9: 0 nets processed (0 paths updated) afi 4 forced/ms 1
Oct 12 09:54:14.477 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6777: Import walk: import ver 0 -> 9: 6 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:14.477 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4484  [EV]:7165: Import mode handler for af 'VPNv4 Unicast', loc 1, tablever 9 import ver 9 
Oct 12 09:54:14.477 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4484  [EV]:7099: Try to enter 'Label Allocation' mode for AF 'VPNv4 Unicast', thread 4
Oct 12 09:54:14.477 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4484  [EV]:9672: Entering 'Label Allocation' mode for AF 'VPNv4 Unicast'
Oct 12 09:54:14.478 default-bgp/spkr-tr2-mvpn 0/RP0/CPU0 t4488  [MVPN]:4078: BGP end readonly: force vpn table refresh, vpn afi 4, rc 0
Oct 12 09:54:14.478 default-bgp/spkr-tr2-mvpn 0/RP0/CPU0 t4488  [MVPN]:1556: BGP end readonly: Scan force vpn refresh, vpn afi 4, rc 0
Oct 12 09:54:14.478 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x1, 0x0
Oct 12 09:54:14.478 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:6244: Label RW request for table-id 0xe0000002 mode:0
Oct 12 09:54:14.478 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:6244: Label RW request for table-id 0xe0000002 mode:0
Oct 12 09:54:14.480 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:2178: Label change on table 0xe0000002 (old label:0, new label:24003)
Oct 12 09:54:14.480 default-bgp/spkr-tr2-l2vpn 0/RP0/CPU0 t4468  [L2VPN]:5109: EVPN - L3 Label trigger: afi 0, encap 1
Oct 12 09:54:14.480 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:2237: Allocated label 24003 VRF:(VRF 'TBL:CUST-A (1/1)')  tbl_id:0xe0000002 flags1:0x201 flags2:0x0 mode:0
Oct 12 09:54:14.480 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:2237: Allocated label 24003 VRF:(VRF 'TBL:CUST-A (1/1)')  tbl_id:0xe0000002 flags1:0x201 flags2:0x0 mode:0
Oct 12 09:54:14.480 default-bgp/spkr-tr2-l2vpn 0/RP0/CPU0 t4491  [L2VPN]:5159: EVPN - Receive EVPN L3 Label Update: afi: 0 table_ctx: 0x7c3a440c3cc8 VRF: CUST-A
Oct 12 09:54:14.480 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:963: Sent rsrc-complete notif to LSD
Oct 12 09:54:14.481 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:969: bgp label stop rsrc-complete notif timer - reason 1
Oct 12 09:54:14.481 default-bgp/spkr-tr2-srv6 0/RP0/CPU0 t4468  [SRV6]:1859: SRv6: bgp_srv6_sid_resource_notf_timer loc: 6 resource complete notif sent, caller loc 2
Oct 12 09:54:14.481 default-bgp/spkr-tr2-srv6 0/RP0/CPU0 t4468  [SRV6]:1865: SRv6: bgp_srv6_sid_resource_notf_timer loc: 7 resource complete notif sent, caller loc 2
Oct 12 09:54:14.481 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 0 -> 15, be 6, skp 0  upd 0
Oct 12 09:54:14.481 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 0 -> 15, be 0, skp 0  upd 1
Oct 12 09:54:14.481 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:9643: Try to enter 'RIB Update' mode for AF 'VPNv4 Unicast', thread 8
Oct 12 09:54:14.481 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4468  [EV]:19718: Entering 'RIB Update' mode for AF 'VPNv4 Unicast'
Oct 12 09:54:14.481 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 15, min_fwdtable_version 1, import_version 9,  label_version 15, table_version 15
Oct 12 09:54:14.481 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=5}, walkversion=[1 - 15]: tblver=15, labelver=15
Oct 12 09:54:14.482 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2
Oct 12 09:54:14.482 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:913: SR-policy XTC got EoD notif from XTC: 1
Oct 12 09:54:14.482 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:1713: SR-policy XTC stale NH tunnels sweeped, trigger nh walk 0
Oct 12 09:54:14.482 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:1494: SR-policy null bgp_ctx - afi 23, op table_walk
Oct 12 09:54:14.482 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:1620: SR-policy table walk, afi 23, OP - sweep-stale done, updated 0 nets, 0 locally sourced paths
Oct 12 09:54:14.482 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:1494: SR-policy null bgp_ctx - afi 24, op table_walk
Oct 12 09:54:14.482 default-bgp/spkr-tr2-sr 0/RP0/CPU0 t4487  [SR]:1633: SR-policy table walk, afi 24, OP - sweep-stale done, updated 0 nets, 0 locally sourced paths
Oct 12 09:54:14.483 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:16250: RIB converge flags (VRF 'CUST-A') , 0x5, 1, afi 0 tr_upd_gen:0
Oct 12 09:54:14.483 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:17459: RIB :time_rib_batch tot_pfx = 6, 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 09:54:14.483 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:17487: RIB(CUST-A:Vv4u): ver 1 -> 15 :pfx inst/upd/skip/rem 4/0/0/2
Oct 12 09:54:14.483 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=5}, walkversion=[1 - 15]: minfwdtblver=15(acked=15)
Oct 12 09:54:14.483 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19038:   RIBUPD_END2: parentafi=4, prefixstats={install=4, skip=0, mod=0, wdr=2}, updtrigger=1
Oct 12 09:54:14.483 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4471  [EV]:19593: Entering 'Normal' mode for AF 'VPNv4 Unicast'
Oct 12 09:54:14.483 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4471  [SYNC]:19622: entering READ-WRITE mode, set initsync version for bgp_ctx 'VPNv4 Unicast' to 15
Oct 12 09:54:14.487 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4487  [RIB]:6257: RIB converge flags (VRF 'CUST-A') , 0x7, 0, afi 0 tr_upd_gen:1
Oct 12 09:54:14.487 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4487  [RIB]:6263: RIB converge flags (VRF 'CUST-A') , 0x3, 0, afi 0 tr_upd_gen:1
Oct 12 09:54:14.487 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4487  [RIB]:6296: ACK from RIB: tbl_id 0xe0000002, converged 1, ver 2, pending nhscan 0
Oct 12 09:54:14.487 default-bgp/spkr-tr2-table 0/RP0/CPU0 t4487  [TABLE]:6322: table 'TBL:CUST-A (1/1)' propogate rd version, bgp tbl ver 16
Oct 12 09:54:14.487 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6748: Import walk: import ver 9 -> 16: 0 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:14.487 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0
Oct 12 09:54:14.487 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 15 -> 16, be 0, skp 0  upd 0
Oct 12 09:54:14.487 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 15 -> 16, be 0, skp 0  upd 1
Oct 12 09:54:14.487 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6777: Import walk: import ver 9 -> 16: 6 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:14.493 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:11792: Rx-ed 4 redist routes, 0 firsthop notifs, 0 delete
Oct 12 09:54:14.493 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 16, min_fwdtable_version 15, import_version 16,  label_version 16, table_version 20
Oct 12 09:54:14.493 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[15 - 16]: tblver=20, labelver=16
Oct 12 09:54:14.493 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2
Oct 12 09:54:14.493 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0
Oct 12 09:54:14.493 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6748: Import walk: import ver 16 -> 20: 0 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:14.493 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 16 -> 20, be 0, skp 0  upd 0
Oct 12 09:54:14.493 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 16 -> 20, be 0, skp 0  upd 1
Oct 12 09:54:14.493 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6777: Import walk: import ver 16 -> 20: 4 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[15 - 16]: minfwdtblver=16(acked=16)
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19038:   RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=0, wdr=0}, updtrigger=1
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 20, min_fwdtable_version 16, import_version 20,  label_version 20, table_version 20
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[16 - 20]: tblver=20, labelver=20
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:17459: RIB :time_rib_batch tot_pfx = 4, 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 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:17487: RIB(CUST-A:Vv4u): ver 16 -> 20 :pfx inst/upd/skip/rem 0/4/0/0
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[16 - 20]: minfwdtblver=20(acked=20)
Oct 12 09:54:14.494 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19038:   RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=4, wdr=0}, updtrigger=1
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13190:   UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=20, pureRR=0
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13193:   UPDGEN_UPD3: walkversion=[0 - 20]: resumedver=0, advdisabled=0, completed=1, msgcount=5
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=6, adv=6, wdn=0, skp=0, sup=0, bestext=0}
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=20: msgcount=5
Oct 12 09:54:14.511 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=6, wdn=0, sup=0, skp=0}
Oct 12 09:54:14.512 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:4101:    UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=20: msgcount=1
Oct 12 09:54:14.512 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:4104:      UPDGEN_FG_APPEND2: fg-prefixstats={adv=6, wdn=0, sup=0, skp=0}
Oct 12 09:54:14.512 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4485  [NBR]:12181: table 'TBL:default (1/128)' EoR sent to sub-grp 0.1 upd-grp 2
Oct 12 09:54:15.828 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4482  [NBR]:1431: TBL:default (1/128), EOR rx-ed from vrf 0x60000000 nbr '2.2.2.2', afi:4, accepted prefix count:6, explicit:1
Oct 12 09:54:15.828 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0
Oct 12 09:54:15.828 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 20 -> 26, be 0, skp 0  upd 0
Oct 12 09:54:15.828 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6748: Import walk: import ver 20 -> 26: 0 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:15.828 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 20 -> 26, be 0, skp 0  upd 1
Oct 12 09:54:15.828 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6777: Import walk: import ver 20 -> 26: 6 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:15.829 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6748: Import walk: import ver 26 -> 27: 0 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:15.829 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0
Oct 12 09:54:15.829 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4484  [IMPORT]:6777: Import walk: import ver 26 -> 27: 1 nets processed (0 paths updated) afi 4 forced/ms 0
Oct 12 09:54:15.829 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 26 -> 27, be 0, skp 0  upd 0
Oct 12 09:54:15.829 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4468  [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 26 -> 27, be 0, skp 0  upd 1
Oct 12 09:54:15.829 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 27, min_fwdtable_version 20, import_version 27,  label_version 27, table_version 27
Oct 12 09:54:15.829 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[20 - 27]: tblver=27, labelver=27
Oct 12 09:54:15.829 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2
Oct 12 09:54:15.830 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [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 12 09:54:15.830 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:17487: RIB(CUST-A:Vv4u): ver 20 -> 27 :pfx inst/upd/skip/rem 1/4/0/1
Oct 12 09:54:15.830 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[20 - 27]: minfwdtblver=27(acked=27)
Oct 12 09:54:15.830 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:19038:   RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=4, wdr=1}, updtrigger=1
Oct 12 09:54:15.830 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4471  [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 27, min_fwdtable_version 27, import_version 27,  label_version 27, table_version 27
Oct 12 09:54:15.853 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1
Oct 12 09:54:15.853 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13190:   UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=27, pureRR=0
Oct 12 09:54:15.853 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13193:   UPDGEN_UPD3: walkversion=[20 - 27]: resumedver=0, advdisabled=0, completed=1, msgcount=0
Oct 12 09:54:15.853 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13195:   UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0}
Oct 12 09:54:15.853 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13197:   UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0}
Oct 12 09:54:15.853 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4485  [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 27
Oct 12 09:54:46.353 bgp/bpm-tr2-apic 0/RP0/CPU0 t4402  [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 09:55:17.545 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4481  [COMMON]:857: vrf 0x60000000 nbr 0x7c3a449acb40, set peer ack version to 20, afi 4, loc 2
Oct 12 09:55:17.545 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4481  [COMMON]:1000: vrf 0x60000000 nbr 0x7c3a449acb40, set peer ack version to 27, afi 4, loc 3
Oct 12 09:56:17.478 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4488  [SYNC]:2280: reached initsync version 15 for afi VPNv4 Unicast,  min_nbr_ack version 27, scanner_runs 3
Oct 12 09:56:17.478 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4488  [SYNC]:1792: NSR event: 'End of Convergence' on active
Oct 12 09:56:17.478 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4488  [SYNC]:1792: NSR event: 'End of Convergence' on active
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4486  [SYNC]:6368: active NSR state transition for 'Standby Sync-group', event 'End of Convergence', 'Active Ready' -> 'Active Ready', mask 0x12
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4486  [SYNC]:6368: active NSR state transition for 'ISSU Sync-group', event 'End of Convergence', 'None' -> 'None', mask 0x10
Oct 12 09:56:17.479 default-bgp/spkr-tr2-issu 0/RP0/CPU0 t4486  [ISSU]:541: ISM: ISSU Not enabled
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4486  [SYNC]:6460: send active up msg to 'ISSU Sync-group' 'Unknown', after NSR restart or tcp restart
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4486  [SYNC]:5241: scoped sync: trigger forced cleanup, batch state 0, count 0
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4416  [SYNC]:4950: scoped sync: forced cleanup, batch state 0
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4416  [SYNC]:4952: scoped sync: batch state change from 0 to 0
Oct 12 09:56:17.479 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4486  [SYNC]:8714: scoped sync: skip sending table update done, global NSR not ready