RP/0/RP0/CPU0:PE1#show bgp trace | utility tail -n 150 Sun Sep 27 16:51:07.235 UTC Sep 27 16:34:33.654 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:3392: TBL:CUST-A (1/1) delete subgrp UG/SG:2/2 subgrp:0x7d02ec00b6b0 caller 0x60d892d5ce0f Sep 27 16:34:33.654 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659 [NBR]:5297: bgp_default_originate table:0x7d032f732cc8 nbr:0x7d033c0184c0 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 Sep 27 16:34:33.654 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 1 Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=19, pureRR=0 Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=7, adv=3, wdn=0, skp=4, sup=0, bestext=0} Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=2, walktype=0, targetver=19: msgcount=2 Sep 27 16:34:33.684 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=3, wdn=0, sup=0, skp=4} Sep 27 16:34:33.684 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4656 [COMMON]:857: vrf 0x60000002 nbr 0x7d033c0184c0, set peer ack version to 19, afi 0, loc 2 Sep 27 16:34:33.684 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4656 [COMMON]:857: vrf 0x60000002 nbr 0x7d033c0184c0, set peer ack version to 19, afi 0, loc 2 Sep 27 16:35:29.650 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:584: bpm chkpt remove: inst-id 0, buff-seq 7, total count 3, delete count 1, ok 1 Sep 27 16:35:29.650 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:584: bpm chkpt remove: inst-id 0, buff-seq 8, total count 2, delete count 2, ok 1 Sep 27 16:35:29.650 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [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 2, elements 0 Sep 27 16:35:29.650 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:913: func txlist_timer_expiry, event restart, p1 1 p2 60 p3 1 Sep 27 16:35:31.651 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1062: Update gen release-index bit operation TBL:CUST-A (1/1) fgrp idx/size 0 Sep 27 16:35:31.651 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A (1/1) fgrp idx/size 1 Sep 27 16:35:31.651 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A (1/1) fgrp idx/size 3 Sep 27 16:35:33.654 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:2 subgrp:0x7d02ec00b6b0 Sep 27 16:35:42.471 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4656 [COMMON]:857: vrf 0x60000002 nbr 0x7d033c0184c0, set peer ack version to 19, afi 0, loc 2 Sep 27 16:36:29.651 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [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 Sep 27 16:41:16.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4655 [UPD]:3161: Updgen - Received refresh request for neighbor 172.16.1.2 afi IPv4 Unicast, nbr version 19, read seq no 713 Sep 27 16:41:16.298 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4655 [POSTIT]:5163: skip postit: 'Route-refresh request received', nbr: 172.16.1.2, reason: 1 Sep 27 16:41:16.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4655 [UPD]:939: Updgen - Processing parallel route refresh for neighbor 172.16.1.2, afi IPv4 Unicast, refresh target version 19,rt-size 0, parallel refresh 0, resend 0 Sep 27 16:41:16.298 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659 [NBR]:5297: bgp_default_originate table:0x7d032f732cc8 nbr:0x7d033c0184c0 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:00026700 Sep 27 16:41:18.298 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4662 [POSTIT]:7502: skip pending postit: nbr: 172.16.1.2, afi: 0, global nsr not ready Sep 27 16:41:18.298 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4662 [COMMON]:6095: nbr_ptr 0x7d033c0184c0 resend updates, afi IPv4 Unicast, versions: 19, 0 fl:0x2 Sep 27 16:41:18.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:6223: Created subgrp:1(0x7d02dc0173e0) refr:1 for nbr 172.16.1.2 (vrf CUST-A), afi 0 version 19 Sep 27 16:41:18.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.1.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.2 rtset 0 Sep 27 16:41:18.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.0 rtset 2 Sep 27 16:41:18.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2579: Filter-group op (Filter-group Add Rf Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:vrf:CUST-A:Nbr:172.16.1.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.2 rtset 0 Sep 27 16:41:18.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2608: created filtergrp 2 for vrf 0x60000002 nbr 172.16.1.2, afi 0, subgrp version 0, refresh 1 Sep 27 16:41:18.298 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659 [NBR]:5297: bgp_default_originate table:0x7d032f732cc8 nbr:0x7d033c0184c0 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:00076700 Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=2(Refresh), inputtargetver=19, pureRR=0 Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=7, adv=3, wdn=0, skp=4, sup=0, bestext=0} Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=2, walktype=2, targetver=19: msgcount=2 Sep 27 16:41:18.327 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=3, wdn=0, sup=0, skp=4} Sep 27 16:41:18.328 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 19 Sep 27 16:42:27.481 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4656 [COMMON]:896: vrf 0x60000002 nbr_ptr 0x7d033c0184c0, set refresh peer ack version to 19, afi 0, loc 2 Sep 27 16:42:54.979 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:1396: Updgen - Refresh request for neighbor 172.16.1.2 afi IPv4 Unicast completed. Refresh target version 19 Sep 27 16:42:54.980 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1975: Filter-group op (Filter-group Rm rfresh Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-ANbr:172.16.1.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.2 rtset 1 Sep 27 16:42:54.980 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.0 rtset 1 Sep 27 16:42:54.980 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:1 subgrp:0x7d02dc0173e0 Sep 27 16:42:54.980 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 2 Sep 27 16:43:54.980 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4662 [EV]:1594: Nbr 172.16.1.2 version catchup for afi 0 Sep 27 16:45:54.981 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4662 [EV]:1594: Nbr 172.16.1.2 version catchup for afi 0 Sep 27 16:47:14.651 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4643 [LABEL]:3971: Label RPF download LSD | processed: 0 err: 0 Sep 27 16:47:54.982 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4662 [EV]:1594: Nbr 172.16.1.2 version catchup for afi 0 Sep 27 16:47:59.101 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4655 [UPD]:3161: Updgen - Received refresh request for neighbor 172.16.1.2 afi IPv4 Unicast, nbr version 19, read seq no 850 Sep 27 16:47:59.101 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4655 [POSTIT]:5163: skip postit: 'Route-refresh request received', nbr: 172.16.1.2, reason: 1 Sep 27 16:47:59.101 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4655 [UPD]:939: Updgen - Processing parallel route refresh for neighbor 172.16.1.2, afi IPv4 Unicast, refresh target version 19,rt-size 0, parallel refresh 0, resend 0 Sep 27 16:47:59.101 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659 [NBR]:5297: bgp_default_originate table:0x7d032f732cc8 nbr:0x7d033c0184c0 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:00026700 Sep 27 16:48:01.101 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4662 [POSTIT]:7502: skip pending postit: nbr: 172.16.1.2, afi: 0, global nsr not ready Sep 27 16:48:01.101 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4662 [COMMON]:6095: nbr_ptr 0x7d033c0184c0 resend updates, afi IPv4 Unicast, versions: 19, 0 fl:0x2 Sep 27 16:48:01.102 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:6223: Created subgrp:2(0x7d02dc0173e0) refr:1 for nbr 172.16.1.2 (vrf CUST-A), afi 0 version 19 Sep 27 16:48:01.102 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.1.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.2 rtset 0 Sep 27 16:48:01.102 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.0 rtset 2 Sep 27 16:48:01.102 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2579: Filter-group op (Filter-group Add Rf Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:vrf:CUST-A:Nbr:172.16.1.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.2 rtset 0 Sep 27 16:48:01.102 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2608: created filtergrp 2 for vrf 0x60000002 nbr 172.16.1.2, afi 0, subgrp version 0, refresh 1 Sep 27 16:48:01.102 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659 [NBR]:5297: bgp_default_originate table:0x7d032f732cc8 nbr:0x7d033c0184c0 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:00076700 Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=2(Refresh), inputtargetver=19, pureRR=0 Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=7, adv=3, wdn=0, skp=4, sup=0, bestext=0} Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=2, walktype=2, targetver=19: msgcount=2 Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=3, wdn=0, sup=0, skp=4} Sep 27 16:48:01.135 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 19 Sep 27 16:48:16.961 bgp/bpm-tr2-gen 0/RP0/CPU0 t4543 [GEN]:4298: BPM verify running (sense=1, asn=65001, inst=default) started Sep 27 16:48:16.961 bgp/bpm-tr2-gen 0/RP0/CPU0 t4543 [GEN]:4383: BPM verify running (sense=1, asn=65001, inst=default) ended Sep 27 16:48:16.970 bgp/bpm-tr2-gen 0/RP0/CPU0 t4543 [GEN]:4547: BPM apply running (sense=1, asn=65001, inst=default) started Sep 27 16:48:16.970 bgp/bpm-tr2-gen 0/RP0/CPU0 t4543 [GEN]:4563: BPM doing apply-running for configuration Sep 27 16:48:16.970 bgp/bpm-tr2-gen 0/RP0/CPU0 t4543 [GEN]:4706: BPM apply running (sense=1, asn=65001, inst=default) ended Sep 27 16:48:17.160 bgp/bpm-tr2-gen 0/RP0/CPU0 t4543 [GEN]:17034: BPM Forward-ref ua-exists: ent - , vrf - CUST-A, inst - default, fr - 0 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:340: bpm gen notify: inst-name default, inst-id 0, total publish 32, chkpt count 3 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:447: bpm chkpt notify: Chkpt status during 'EDM Gen', ok 1 finished 0 objid-is-null? 1 loc 0 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:2040: bpm build txlist: task 1 [1-last-batch,0-more] , inst-id 0, item-count 3, chkpt-count 3, total-item 32, buff-seq 9 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:596: bpm buff pack, bscan 0 inst-id 0, buff-seq 9, item-count 3, msg-size 412 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:482: Sending init config done signal to inst_id 0, rc 0, loc 1 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:535: bpm chkpt notify: Chkpt status after 1 [1-EDM Gen, 2-Monitor Del], bpm_edm_chkpt_items 0, item_cnt 3 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:302: func bpm_edm_generate_notifications, event can't find instinfo, instance_id 1 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:302: func bpm_edm_generate_notifications, event can't find instinfo, instance_id 2 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:302: func bpm_edm_generate_notifications, event can't find instinfo, instance_id 3 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:373: bpm send msg, role 0 (1:S, 0:A)inst-id 0, buff-seq 9, msg-id 5, rc 0 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:398: bpm txlist handler end, role 0 (1:S, 0:A)inst-id 0, send_status 1 [1:ok, 2:blocked, 3:failed] elem-count 1, rc 0 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:210: bpm txlist show:loc 1 [1:txlist-handler, 2:conn-up 3:timer-cleanup] active-mem 1, suspend-mem 0, end-list 1, remove-mem 0, elements 1 Sep 27 16:48:18.970 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:299: func txlist-no-active-mem, TXLIST cleanup start timer :60 Sep 27 16:48:18.970 default-bgp/spkr-tr2-apic 0/RP0/CPU0 t4623 [AIPC]:1619: func bgp_bpm_ipc_cb: type, event input-wait, p1 3 p2 0 p3 1 Sep 27 16:48:18.971 default-bgp/spkr-tr2-apic 0/RP0/CPU0 t4623 [AIPC]:1358: bgp ipc rcv, inst-id 0, msg-len 412, buff-seq 9, msg count 3, buff-rel 1, init-config 1 [0-not Done , 1-Done] Sep 27 16:48:18.971 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4659 [POSTIT]:5163: skip postit: 'As-override config change', nbr: 172.16.1.2, reason: 1 Sep 27 16:48:19.171 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:2504: func bpm_ipc_cb: type, event send-status, p1 10 p2 0 p3 0 Sep 27 16:48:19.171 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:525: bpm send status, role 0 (1:S, 0:A)inst-id 0, msg-id 5, send-ok 1, rel buff-seq 0 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:7958: trying to find update group for nbr 0x7d033c0184c0, afi IPv4 Unicast Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:6921: created update group for table TBL:CUST-A (1/1), index 0.1, nbr 172.16.1.2, flags 0x64000 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1975: Filter-group op (Filter-group Rm rfresh Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-ANbr:172.16.1.2) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 1 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.0 rtset 1 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:1 subgrp:0x7d02dc0173e0 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [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.1 updgrp 0.2 rtset 0 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.0 rtset 2 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:6967: Delete update group for table TBL:CUST-A (1/1), index 0.2 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.1.2) fgrp idx 0.2 subgrp idx 0.1 updgrp 0.1 rtset 0 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.1 subgrp idx 0.2 updgrp 0.0 rtset 1 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [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.1 subgrp idx 0.2 updgrp 0.1 rtset 0 Sep 27 16:48:20.971 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:2818: created filtergrp 1 for vrf 0x60000002 nbr 172.16.1.2, afi 0, subgrp version 0, refresh 0 Sep 27 16:48:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4592: Created subgrp:2(0x7d02ec00ae60) refr:0 for nbr 172.16.1.2 (vrf CUST-A), afi 0 version 0 Sep 27 16:48:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:7104: added vrf 0x60000002 nbr 172.16.1.2 to update group 0.1, afi IPv4 Unicast, flags 0x64000 Sep 27 16:48:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:1 subgrp:0x7d02dc016cf0 Sep 27 16:48:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 2 Sep 27 16:48:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 2 Sep 27 16:48:20.972 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4662 [POSTIT]:5163: skip postit: 'None', nbr: 172.16.1.2, reason: 1 Sep 27 16:48:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:957: Updgen - Resending table to neighbor 172.16.1.2, afi IPv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.1, subgrp=0.2 Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.2, walktype=0(Regular), inputtargetver=19, pureRR=0 Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=4 Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=7, adv=6, wdn=0, skp=1, sup=0, bestext=0} Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=1, FG=1, walktype=0, targetver=19: msgcount=4 Sep 27 16:48:21.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=6, wdn=0, sup=0, skp=1} Sep 27 16:48:22.972 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4662 [POSTIT]:7502: skip pending postit: nbr: 172.16.1.2, afi: 0, global nsr not ready Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:IPv4 Unicast:Nbr:172.16.1.2) fgrp idx 0.1 subgrp idx 0.2 updgrp 0.1 rtset 0 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.1 subgrp idx 0.2 updgrp 0.0 rtset 2 Sep 27 16:48:22.972 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4662 [COMMON]:6095: nbr_ptr 0x7d033c0184c0 resend updates, afi IPv4 Unicast, versions: 0, 0 fl:0x4 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(172.16.1.2) fgrp idx 0.1 subgrp idx 0.2 updgrp 0.1 rtset 0 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:CUST-A (1/1)) fgrp idx 0.3 subgrp idx 0.1 updgrp 0.0 rtset 1 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:IPv4 Unicast:Vrf:CUST-A:Nbr:172.16.1.2) fgrp idx 0.3 subgrp idx 0.1 updgrp 0.1 rtset 0 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:2818: created filtergrp 3 for vrf 0x60000002 nbr 172.16.1.2, afi 0, subgrp version 0, refresh 0 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4662 [UPD]:3392: TBL:CUST-A (1/1) delete subgrp UG/SG:1/2 subgrp:0x7d02ec00ae60 caller 0x60d892d5ce0f Sep 27 16:48:22.972 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4659 [NBR]:5297: bgp_default_originate table:0x7d032f732cc8 nbr:0x7d033c0184c0 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 Sep 27 16:48:22.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1381: Update gen Start bit operation Filtergrp delete-timer fgrp idx/size 1 Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.1, subgrp=0.1 Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=19, pureRR=0 Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 19]: resumedver=0, advdisabled=0, completed=1, msgcount=4 Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=7, adv=6, wdn=0, skp=1, sup=0, bestext=0} Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=1, FG=3, walktype=0, targetver=19: msgcount=4 Sep 27 16:48:23.003 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=6, wdn=0, sup=0, skp=1} Sep 27 16:48:23.003 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4656 [COMMON]:857: vrf 0x60000002 nbr 0x7d033c0184c0, set peer ack version to 19, afi 0, loc 2 Sep 27 16:49:18.971 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:584: bpm chkpt remove: inst-id 0, buff-seq 9, total count 3, delete count 3, ok 1 Sep 27 16:49:18.971 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [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 Sep 27 16:49:18.971 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [AIPC]:913: func txlist_timer_expiry, event restart, p1 1 p2 60 p3 1 Sep 27 16:49:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1062: Update gen release-index bit operation TBL:CUST-A (1/1) fgrp idx/size 0 Sep 27 16:49:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A (1/1) fgrp idx/size 1 Sep 27 16:49:20.972 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:1079: Update gen release-index for bit operation TBL:CUST-A (1/1) fgrp idx/size 2 Sep 27 16:49:22.973 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4659 [UPD]:3184: TBL:CUST-A (1/1) free subgrp SG:2 subgrp:0x7d02ec00ae60 Sep 27 16:49:27.490 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4656 [COMMON]:857: vrf 0x60000002 nbr 0x7d033c0184c0, set peer ack version to 19, afi 0, loc 2 Sep 27 16:50:18.971 bgp/bpm-tr2-apic 0/RP0/CPU0 t4610 [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