RP/0/RP0/CPU0:PE1#show bgp trace | utility tail -n 150 Mon Oct 12 00:49:24.058 UTC Oct 12 00:35:02.649 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=5, wdn=0, sup=0, skp=1} Oct 12 00:35:02.649 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 12 00:35:02.649 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=18, pureRR=0 Oct 12 00:35:02.649 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[17 - 18]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 00:35:02.649 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 12 00:35:02.649 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:35:02.649 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=18: msgcount=1 Oct 12 00:35:02.649 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 12 00:35:02.649 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=18: msgcount=1 Oct 12 00:35:02.649 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 12 00:35:02.649 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4678 [EV]:11966: Notified SysMgr of availability (Nbrs converged) Oct 12 00:35:04.607 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4681 [POSTIT]:7502: skip pending postit: nbr: 172.16.1.2, afi: 0, global nsr not ready Oct 12 00:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Nbr:172.16.1.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 00:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [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:35:04.607 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4681 [COMMON]:6095: nbr_ptr 0x7b1b4f4424c0 resend updates, afi IPv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 00:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.1.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 00:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [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:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-A:Nbr:172.16.1.2) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 00:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2818: created filtergrp 2 for vrf 0x60000002 nbr 172.16.1.2, afi 0, subgrp version 0, refresh 0 Oct 12 00:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:3392: TBL:CUST-A (1/1) delete subgrp UG/SG:2/1 subgrp:0x7b1afc00b6b0 caller 0x5e20c3e06e0f Oct 12 00:35:04.607 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4678 [NBR]:5297: bgp_default_originate table:0x7b1b4eb3ccc8 nbr:0x7b1b4f4424c0 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:35:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 1 Oct 12 00:35:04.648 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.2 Oct 12 00:35:04.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.2, walktype=0(Regular), inputtargetver=18, pureRR=0 Oct 12 00:35:04.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 18]: resumedver=0, advdisabled=0, completed=1, msgcount=3 Oct 12 00:35:04.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=6, adv=5, wdn=0, skp=1, sup=0, bestext=0} Oct 12 00:35:04.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:35:04.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=2, walktype=0, targetver=18: msgcount=3 Oct 12 00:35:04.648 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=5, wdn=0, sup=0, skp=1} Oct 12 00:35:04.648 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7b1b4f4424c0, set peer ack version to 18, afi 0, loc 2 Oct 12 00:35:04.748 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4642 [POSTIT]:5163: skip postit: 'Outbound user soft clear', nbr: 172.16.1.2, reason: 1 Oct 12 00:35:04.748 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4681 [POSTIT]:5163: skip postit: 'None', nbr: 172.16.1.2, reason: 1 Oct 12 00:35:04.748 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:957: Updgen - Resending table to neighbor 172.16.1.2, afi IPv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Oct 12 00:35:06.492 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4681 [COMMON]:2504: nbr soft-reconfig 'ptr: 0x7b1b4f4424c0',afi: 0 af_flag: 0x0b1100a4 af3_flag: 0x00080000 loc: 1 Oct 12 00:35:06.492 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4681 [COMMON]:2571: soft-reconfig delayed bestpath: elapsed time(ms) 0, total run 0 , afi IPv4 Unicast, count 1 Oct 12 00:35:06.749 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4681 [POSTIT]:7502: skip pending postit: nbr: 172.16.1.2, afi: 0, global nsr not ready Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Nbr:172.16.1.2) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.0 rtset 2 Oct 12 00:35:06.749 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4681 [COMMON]:6095: nbr_ptr 0x7b1b4f4424c0 resend updates, afi IPv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.1.2) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.3 subgrp idx 0.3 updgrp 0.0 rtset 2 Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-A:Nbr:172.16.1.2) fgrp idx 0.3 subgrp idx 0.3 updgrp 0.2 rtset 0 Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:2818: created filtergrp 3 for vrf 0x60000002 nbr 172.16.1.2, afi 0, subgrp version 0, refresh 0 Oct 12 00:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4681 [UPD]:3392: TBL:CUST-A (1/1) delete subgrp UG/SG:2/2 subgrp:0x7b1af4016d00 caller 0x5e20c3e06e0f Oct 12 00:35:06.749 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4678 [NBR]:5297: bgp_default_originate table:0x7b1b4eb3ccc8 nbr:0x7b1b4f4424c0 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:35:06.749 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 2 Oct 12 00:35:06.779 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.3 Oct 12 00:35:06.779 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.3, walktype=0(Regular), inputtargetver=18, pureRR=0 Oct 12 00:35:06.779 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 18]: resumedver=0, advdisabled=0, completed=1, msgcount=3 Oct 12 00:35:06.779 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=6, adv=5, wdn=0, skp=1, sup=0, bestext=0} Oct 12 00:35:06.779 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:35:06.779 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=3, walktype=0, targetver=18: msgcount=3 Oct 12 00:35:06.779 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=5, wdn=0, sup=0, skp=1} Oct 12 00:35:06.779 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7b1b4f4424c0, set peer ack version to 18, afi 0, loc 2 Oct 12 00:35:13.718 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 00:35:13.718 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 18 -> 19, be 0, skp 0 upd 0 Oct 12 00:35:13.718 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 18 -> 19, be 0, skp 0 upd 1 Oct 12 00:35:13.718 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 18 -> 19: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:35:13.718 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 18 -> 19: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:35:13.718 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 19, min_fwdtable_version 18, import_version 19, label_version 19, table_version 19 Oct 12 00:35:13.718 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[18 - 19]: tblver=19, labelver=19 Oct 12 00:35:13.718 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 12 00:35:13.719 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [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 12 00:35:13.719 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:17487: RIB(CUST-A:Vv4u): ver 18 -> 19 :pfx inst/upd/skip/rem 0/1/0/0 Oct 12 00:35:13.719 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[18 - 19]: minfwdtblver=19(acked=19) Oct 12 00:35:13.719 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=0}, updtrigger=1 Oct 12 00:35:13.742 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 12 00:35:13.742 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=19, pureRR=0 Oct 12 00:35:13.742 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[18 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 00:35:13.742 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 00:35:13.742 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:35:13.742 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 19 Oct 12 00:35:13.748 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.3 Oct 12 00:35:13.748 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.3, walktype=0(Regular), inputtargetver=19, pureRR=0 Oct 12 00:35:13.748 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[18 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 00:35:13.748 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 12 00:35:13.748 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:35:13.748 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=3, walktype=0, targetver=19: msgcount=1 Oct 12 00:35:13.748 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 12 00:35:13.748 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7b1b4f4424c0, set peer ack version to 18, afi 0, loc 2 Oct 12 00:36:00.605 bgp/bpm-tr2-apic 0/RP0/CPU0 t4624 [AIPC]:584: bpm chkpt remove: inst-id 0, buff-seq 7, total count 3, delete count 3, ok 1 Oct 12 00:36:00.605 bgp/bpm-tr2-apic 0/RP0/CPU0 t4624 [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 00:36:00.605 bgp/bpm-tr2-apic 0/RP0/CPU0 t4624 [AIPC]:913: func txlist_timer_expiry, event restart, p1 1 p2 60 p3 1 Oct 12 00:36:02.606 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 12 00:36:02.606 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 2 Oct 12 00:36:02.606 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 12 00:36:02.606 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 4 Oct 12 00:36:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:2 subgrp:0x7b1af4016d00 Oct 12 00:36:04.607 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:1 subgrp:0x7b1afc00b6b0 Oct 12 00:36:16.478 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7b1b4f4424c0, set peer ack version to 19, afi 0, loc 2 Oct 12 00:36:16.478 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7b1b4f444000, set peer ack version to 18, afi 4, loc 2 Oct 12 00:36:16.478 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7b1b4f444000, set peer ack version to 19, afi 4, loc 3 Oct 12 00:36:16.478 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7b1b4f445b40, set peer ack version to 18, afi 4, loc 2 Oct 12 00:36:16.478 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7b1b4f445b40, set peer ack version to 19, afi 4, loc 3 Oct 12 00:37:00.605 bgp/bpm-tr2-apic 0/RP0/CPU0 t4624 [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:40:57.305 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 00:40:57.305 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 19 -> 20, be 0, skp 0 upd 0 Oct 12 00:40:57.305 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 19 -> 20, be 0, skp 0 upd 1 Oct 12 00:40:57.305 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 19 -> 20: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:40:57.305 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 19 -> 20: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:40:57.305 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 20, min_fwdtable_version 19, import_version 20, label_version 20, table_version 20 Oct 12 00:40:57.305 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[19 - 20]: tblver=20, labelver=20 Oct 12 00:40:57.305 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 12 00:40:57.306 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [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 12 00:40:57.306 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:17487: RIB(CUST-A:Vv4u): ver 19 -> 20 :pfx inst/upd/skip/rem 0/1/0/0 Oct 12 00:40:57.306 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[19 - 20]: minfwdtblver=20(acked=20) Oct 12 00:40:57.306 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=0}, updtrigger=1 Oct 12 00:40:57.333 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.3 Oct 12 00:40:57.333 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.3, walktype=0(Regular), inputtargetver=20, pureRR=0 Oct 12 00:40:57.333 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[19 - 20]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 00:40:57.333 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=0, sup=1, bestext=0} Oct 12 00:40:57.333 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:40:57.333 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000002 nbr 0x7b1b4f4424c0, set peer ack version to 20, afi 0, loc 4 Oct 12 00:40:57.334 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(3) UG:(2) end 20 Oct 12 00:40:57.336 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 12 00:40:57.336 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=20, pureRR=0 Oct 12 00:40:57.336 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[19 - 20]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 00:40:57.336 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 00:40:57.336 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:40:57.336 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000000 nbr 0x7b1b4f444000, set peer ack version to 20, afi 4, loc 4 Oct 12 00:40:57.336 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000000 nbr 0x7b1b4f445b40, set peer ack version to 20, afi 4, loc 4 Oct 12 00:40:57.336 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 20 Oct 12 00:46:37.753 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 20 -> 21: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:46:37.753 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 20 -> 21: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 00:46:37.753 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 00:46:37.753 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 20 -> 21, be 0, skp 0 upd 0 Oct 12 00:46:37.753 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4661 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 20 -> 21, be 0, skp 0 upd 1 Oct 12 00:46:37.753 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 21, min_fwdtable_version 20, import_version 21, label_version 21, table_version 21 Oct 12 00:46:37.753 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[20 - 21]: tblver=21, labelver=21 Oct 12 00:46:37.753 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 12 00:46:37.754 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [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 12 00:46:37.754 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:17487: RIB(CUST-A:Vv4u): ver 20 -> 21 :pfx inst/upd/skip/rem 0/1/0/0 Oct 12 00:46:37.754 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[20 - 21]: minfwdtblver=21(acked=21) Oct 12 00:46:37.754 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4666 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=0}, updtrigger=1 Oct 12 00:46:37.781 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.3 Oct 12 00:46:37.781 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.3, walktype=0(Regular), inputtargetver=21, pureRR=0 Oct 12 00:46:37.781 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[20 - 21]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 00:46:37.781 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 12 00:46:37.781 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:46:37.781 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=3, walktype=0, targetver=21: msgcount=1 Oct 12 00:46:37.781 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 12 00:46:37.783 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 12 00:46:37.783 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=21, pureRR=0 Oct 12 00:46:37.783 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[20 - 21]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 00:46:37.783 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 00:46:37.783 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 00:46:37.783 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000000 nbr 0x7b1b4f444000, set peer ack version to 21, afi 4, loc 4 Oct 12 00:46:37.783 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000000 nbr 0x7b1b4f445b40, set peer ack version to 21, afi 4, loc 4 Oct 12 00:46:37.783 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 21 Oct 12 00:47:46.496 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7b1b4f4424c0, set peer ack version to 21, afi 0, loc 2