RP/0/RP0/CPU0:PE1#show bgp trace | utility tail -n 150 Tue Oct 13 00:03:35.863 UTC Oct 12 23:52:04.888 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 23:52:04.888 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=26, pureRR=0 Oct 12 23:52:04.888 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[25 - 26]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 23:52:04.888 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 23:52:04.888 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:04.888 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 26 Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=26, pureRR=0 Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[25 - 26]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=26: msgcount=1 Oct 12 23:52:04.894 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Oct 12 23:52:04.894 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000002 nbr 0x7e69e651a4c0, set peer ack version to 20, afi 0, loc 2 Oct 12 23:52:06.862 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 23:52:06.862 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 26 -> 27, be 0, skp 0 upd 0 Oct 12 23:52:06.862 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 26 -> 27, be 0, skp 0 upd 1 Oct 12 23:52:06.862 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4455 [IMPORT]:6748: Import walk: import ver 26 -> 27: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 23:52:06.862 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4455 [IMPORT]:6777: Import walk: import ver 26 -> 27: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 23:52:06.862 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 27, min_fwdtable_version 26, import_version 27, label_version 27, table_version 27 Oct 12 23:52:06.862 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[26 - 27]: tblver=27, labelver=27 Oct 12 23:52:06.863 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 12 23:52:06.863 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [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 23:52:06.863 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:17487: RIB(CUST-A:Vv4u): ver 26 -> 27 :pfx inst/upd/skip/rem 1/0/0/0 Oct 12 23:52:06.863 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[26 - 27]: minfwdtblver=27(acked=27) Oct 12 23:52:06.863 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=0, wdr=0}, updtrigger=1 Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=27, pureRR=0 Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[26 - 27]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=27: msgcount=1 Oct 12 23:52:06.890 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Oct 12 23:52:06.891 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000002 nbr 0x7e69e651a4c0, set peer ack version to 26, afi 0, loc 2 Oct 12 23:52:06.893 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 23:52:06.893 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=27, pureRR=0 Oct 12 23:52:06.893 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[26 - 27]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 23:52:06.893 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 23:52:06.893 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:06.893 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 27 Oct 12 23:52:11.644 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 23:52:11.645 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 27 -> 28, be 0, skp 0 upd 0 Oct 12 23:52:11.645 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4455 [IMPORT]:6748: Import walk: import ver 27 -> 28: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 23:52:11.645 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 27 -> 28, be 0, skp 0 upd 1 Oct 12 23:52:11.645 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4455 [IMPORT]:6777: Import walk: import ver 27 -> 28: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 23:52:11.645 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 28, min_fwdtable_version 27, import_version 28, label_version 28, table_version 28 Oct 12 23:52:11.645 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[27 - 28]: tblver=28, labelver=28 Oct 12 23:52:11.645 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 12 23:52:11.646 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [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 23:52:11.646 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:17487: RIB(CUST-A:Vv4u): ver 27 -> 28 :pfx inst/upd/skip/rem 1/0/0/0 Oct 12 23:52:11.646 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[27 - 28]: minfwdtblver=28(acked=28) Oct 12 23:52:11.646 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=0, wdr=0}, updtrigger=1 Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=28, pureRR=0 Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[27 - 28]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=28: msgcount=1 Oct 12 23:52:11.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Oct 12 23:52:11.672 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000002 nbr 0x7e69e651a4c0, set peer ack version to 27, afi 0, loc 2 Oct 12 23:52:11.675 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 23:52:11.675 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=28, pureRR=0 Oct 12 23:52:11.675 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[27 - 28]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 23:52:11.675 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 23:52:11.675 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:11.675 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 28 Oct 12 23:52:13.641 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 23:52:13.641 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 28 -> 29, be 0, skp 0 upd 0 Oct 12 23:52:13.641 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4442 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 28 -> 29, be 0, skp 0 upd 1 Oct 12 23:52:13.641 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4455 [IMPORT]:6748: Import walk: import ver 28 -> 29: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 23:52:13.641 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 28, min_fwdtable_version 28, import_version 28, label_version 29, table_version 29 Oct 12 23:52:13.641 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4455 [IMPORT]:6777: Import walk: import ver 28 -> 29: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 23:52:13.641 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 29, min_fwdtable_version 28, import_version 29, label_version 29, table_version 29 Oct 12 23:52:13.641 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[28 - 29]: tblver=29, labelver=29 Oct 12 23:52:13.641 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 12 23:52:13.642 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [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 23:52:13.642 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:17487: RIB(CUST-A:Vv4u): ver 28 -> 29 :pfx inst/upd/skip/rem 1/0/0/0 Oct 12 23:52:13.642 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[28 - 29]: minfwdtblver=29(acked=29) Oct 12 23:52:13.642 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4445 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=0, wdr=0}, updtrigger=1 Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=29, pureRR=0 Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[28 - 29]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=29: msgcount=1 Oct 12 23:52:13.669 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Oct 12 23:52:13.670 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000002 nbr 0x7e69e651a4c0, set peer ack version to 28, afi 0, loc 2 Oct 12 23:52:13.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 23:52:13.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=29, pureRR=0 Oct 12 23:52:13.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[28 - 29]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 23:52:13.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Oct 12 23:52:13.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:13.672 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 29 Oct 12 23:52:16.447 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4396 [POSTIT]:5163: skip postit: 'Outbound user soft clear', nbr: 2.2.2.2, reason: 1 Oct 12 23:52:16.447 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4459 [POSTIT]:5163: skip postit: 'None', nbr: 2.2.2.2, reason: 1 Oct 12 23:52:16.447 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:957: Updgen - Resending table to neighbor 2.2.2.2, afi VPNv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Oct 12 23:52:18.260 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4459 [COMMON]:2504: nbr soft-reconfig 'ptr: 0x7e69e651db40',afi: 4 af_flag: 0x011100a4 af3_flag: 0x00080000 loc: 1 Oct 12 23:52:18.260 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4459 [COMMON]:2571: soft-reconfig delayed bestpath: elapsed time(ms) 0, total run 0 , afi VPNv4 Unicast, count 1 Oct 12 23:52:18.448 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4459 [POSTIT]:7502: skip pending postit: nbr: 2.2.2.2, afi: 4, global nsr not ready Oct 12 23:52:18.448 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:VPNv4 Unicast:Nbr:2.2.2.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 23:52:18.448 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4459 [COMMON]:6095: nbr_ptr 0x7e69e651db40 resend updates, afi VPNv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 23:52:18.448 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(2.2.2.2) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 23:52:18.448 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:default (1/128)) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.0 rtset 2 Oct 12 23:52:18.448 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [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.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 23:52:18.448 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2818: created filtergrp 2 for vrf 0x60000000 nbr 2.2.2.2, afi 4, subgrp version 0, refresh 0 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.2 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.2, walktype=0(Regular), inputtargetver=29, pureRR=0 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 29]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=6, adv=2, wdn=0, skp=4, sup=0, bestext=0} Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=2, walktype=0, targetver=29: msgcount=2 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=4} Oct 12 23:52:18.477 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000000 nbr 0x7e69e651db40, set peer ack version to 25, afi 4, loc 2 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:5482: Resync AF 'VPNv4 Unicast' upd-grp 0.2: Merged sub-grp 0.1 (1 nbrs) and sub-grp 0.2 (1 nbrs) into sub-grp 0.2 (2 nbrs) Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2951: Resynch VPNv4 Unicast Filter-group subgrp 0.2: merged f-group 0.1 (1 nbrs) and f-group 0.2 (1 nbrs) into Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:default (1/128)) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.0 rtset 1 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1062: Update gen release-index bit operation TBL:default (1/128) fgrp idx/size 0 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1079: Update gen release-index for bit operation TBL:default (1/128) fgrp idx/size 1 Oct 12 23:52:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:3392: TBL:default (1/128) delete subgrp UG/SG:2/1 subgrp:0x7e698800c690 caller 0x5f72abe42576 Oct 12 23:52:19.856 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4396 [POSTIT]:5163: skip postit: 'Outbound user soft clear', nbr: 4.4.4.4, reason: 1 Oct 12 23:52:19.856 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4459 [POSTIT]:5163: skip postit: 'None', nbr: 4.4.4.4, reason: 1 Oct 12 23:52:19.856 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:957: Updgen - Resending table to neighbor 4.4.4.4, afi VPNv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Oct 12 23:52:21.574 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4459 [COMMON]:2504: nbr soft-reconfig 'ptr: 0x7e69e651c000',afi: 4 af_flag: 0x011100a4 af3_flag: 0x00080000 loc: 1 Oct 12 23:52:21.574 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4459 [COMMON]:2571: soft-reconfig delayed bestpath: elapsed time(ms) 0, total run 0 , afi VPNv4 Unicast, count 1 Oct 12 23:52:21.856 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4459 [POSTIT]:7502: skip pending postit: nbr: 4.4.4.4, afi: 4, global nsr not ready Oct 12 23:52:21.856 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:VPNv4 Unicast:Nbr:4.4.4.4) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 23:52:21.856 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4459 [COMMON]:6095: nbr_ptr 0x7e69e651c000 resend updates, afi VPNv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 23:52:21.856 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(4.4.4.4) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 23:52:21.856 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1476: Filter-group op (Alloc) Tbl/Nbr(TBL:default (1/128)) fgrp idx 0.1 subgrp idx 0.3 updgrp 0.0 rtset 2 Oct 12 23:52:21.856 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:VPNv4 Unicast:Vrf:default:Nbr:4.4.4.4) fgrp idx 0.1 subgrp idx 0.3 updgrp 0.2 rtset 0 Oct 12 23:52:21.856 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2818: created filtergrp 1 for vrf 0x60000000 nbr 4.4.4.4, afi 4, subgrp version 0, refresh 0 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.3 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.3, walktype=0(Regular), inputtargetver=29, pureRR=0 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 29]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=6, adv=2, wdn=0, skp=4, sup=0, bestext=0} Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=29: msgcount=2 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=4} Oct 12 23:52:21.882 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000000 nbr 0x7e69e651c000, set peer ack version to 25, afi 4, loc 2 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:5482: Resync AF 'VPNv4 Unicast' upd-grp 0.2: Merged sub-grp 0.2 (1 nbrs) and sub-grp 0.3 (1 nbrs) into sub-grp 0.3 (2 nbrs) Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:2951: Resynch VPNv4 Unicast Filter-group subgrp 0.3: merged f-group 0.2 (1 nbrs) and f-group 0.1 (1 nbrs) into Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1566: Filter-group op (Delete) Tbl/Nbr(TBL:default (1/128)) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.0 rtset 1 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1062: Update gen release-index bit operation TBL:default (1/128) fgrp idx/size 0 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:1079: Update gen release-index for bit operation TBL:default (1/128) fgrp idx/size 2 Oct 12 23:52:21.882 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4459 [UPD]:3392: TBL:default (1/128) delete subgrp UG/SG:2/2 subgrp:0x7e6984016d00 caller 0x5f72abe42576 Oct 12 23:53:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:3184: TBL:default (1/128) free subgrp SG:2 subgrp:0x7e6984016d00 Oct 12 23:53:18.477 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4456 [UPD]:3184: TBL:default (1/128) free subgrp SG:1 subgrp:0x7e698800c690 Oct 12 23:53:21.171 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000000 nbr 0x7e69e651db40, set peer ack version to 29, afi 4, loc 2 Oct 12 23:53:21.171 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000002 nbr 0x7e69e651a4c0, set peer ack version to 29, afi 0, loc 2 Oct 12 23:53:36.173 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4453 [COMMON]:857: vrf 0x60000000 nbr 0x7e69e651c000, set peer ack version to 29, afi 4, loc 2