RP/0/RP0/CPU0:PE3#show bgp trace | utility tail -n 150 Sun Sep 27 16:26:50.280 UTC Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=9, pureRR=0 Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 9]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=3, adv=1, wdn=0, skp=2, sup=0, bestext=0} Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=9: msgcount=1 Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=2} Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=9: msgcount=1 Sep 27 16:15:53.298 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=2} Sep 27 16:15:53.298 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4707 [NBR]:12181: table 'TBL:default (1/128)' EoR sent to sub-grp 0.1 upd-grp 2 Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=9, pureRR=0 Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 9]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=3, adv=2, wdn=0, skp=1, sup=0, bestext=0} Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=9: msgcount=2 Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=1} Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=9: msgcount=1 Sep 27 16:15:53.360 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=1} Sep 27 16:15:53.360 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4707 [NBR]:12181: table 'TBL:CUST-A (1/1)' EoR sent to sub-grp 0.1 upd-grp 2 Sep 27 16:15:53.363 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 10, min_fwdtable_version 9, import_version 10, label_version 10, table_version 10 Sep 27 16:15:53.363 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[9 - 10]: tblver=10, labelver=10 Sep 27 16:15:53.363 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Sep 27 16:15:53.363 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[9 - 10]: minfwdtblver=10(acked=10) Sep 27 16:15:53.363 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=0, wdr=0}, updtrigger=1 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=10, pureRR=0 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[9 - 10]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 10 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=10, pureRR=0 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[9 - 10]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:53.390 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 10 Sep 27 16:15:53.469 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4705 [NBR]:1431: TBL:default (1/128), EOR rx-ed from vrf 0x60000000 nbr '2.2.2.2', afi:4, accepted prefix count:2, explicit:1 Sep 27 16:15:53.470 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 10 -> 12, be 0, skp 0 upd 0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 10 -> 12, be 0, skp 0 upd 1 Sep 27 16:15:53.470 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6748: Import walk: import ver 10 -> 12: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6777: Import walk: import ver 10 -> 12: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6748: Import walk: import ver 12 -> 14: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 12, min_fwdtable_version 10, import_version 12, label_version 12, table_version 14 Sep 27 16:15:53.470 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[10 - 12]: tblver=14, labelver=12 Sep 27 16:15:53.470 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6777: Import walk: import ver 12 -> 14: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 12 -> 14, be 0, skp 0 upd 0 Sep 27 16:15:53.470 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 12 -> 14, be 0, skp 0 upd 1 Sep 27 16:15:53.470 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[10 - 12]: minfwdtblver=12(acked=12) Sep 27 16:15:53.470 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=0, wdr=0}, updtrigger=1 Sep 27 16:15:53.470 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 14, min_fwdtable_version 12, import_version 14, label_version 14, table_version 14 Sep 27 16:15:53.470 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[12 - 14]: tblver=14, labelver=14 Sep 27 16:15:53.471 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Sep 27 16:15:53.471 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, rib_calls = 1, total_time = 0 ms, rib_api_time = 0 ms, avg_rib_api_time = 0 ms, max_rib_api_time = 0 ms Sep 27 16:15:53.471 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:17487: RIB(CUST-A:Vv4u): ver 12 -> 14 :pfx inst/upd/skip/rem 2/0/0/0 Sep 27 16:15:53.471 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[12 - 14]: minfwdtblver=14(acked=14) Sep 27 16:15:53.471 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=2, skip=0, mod=0, wdr=0}, updtrigger=1 Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=14, pureRR=0 Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[10 - 14]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=1, wdn=0, skp=1, sup=0, bestext=0} Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=14: msgcount=1 Sep 27 16:15:53.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1} Sep 27 16:15:53.499 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=14: msgcount=1 Sep 27 16:15:53.499 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1} Sep 27 16:15:53.501 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Sep 27 16:15:53.501 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=14, pureRR=0 Sep 27 16:15:53.501 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[10 - 14]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Sep 27 16:15:53.501 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Sep 27 16:15:53.501 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:53.501 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 14 Sep 27 16:15:55.122 default-bgp/spkr-tr2-nbr 0/RP0/CPU0 t4705 [NBR]:1431: TBL:CUST-A (1/1), EOR rx-ed from vrf 0x60000002 nbr '172.16.3.2', afi:0, accepted prefix count:1, explicit:1 Sep 27 16:15:55.123 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Sep 27 16:15:55.123 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6748: Import walk: import ver 14 -> 15: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:15:55.123 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6777: Import walk: import ver 14 -> 15: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:15:55.123 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 14, min_fwdtable_version 14, import_version 15, label_version 14, table_version 15 Sep 27 16:15:55.124 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 14 -> 15, be 1, skp 0 upd 0 Sep 27 16:15:55.124 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 14 -> 15, be 0, skp 0 upd 1 Sep 27 16:15:55.124 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 15, min_fwdtable_version 14, import_version 15, label_version 15, table_version 15 Sep 27 16:15:55.124 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[14 - 15]: tblver=15, labelver=15 Sep 27 16:15:55.124 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Sep 27 16:15:55.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [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 Sep 27 16:15:55.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:17487: RIB(CUST-A:Vv4u): ver 14 -> 15 :pfx inst/upd/skip/rem 1/0/0/0 Sep 27 16:15:55.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[14 - 15]: minfwdtblver=15(acked=15) Sep 27 16:15:55.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=0, wdr=0}, updtrigger=1 Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=15, pureRR=0 Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[14 - 15]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=15: msgcount=1 Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=15: msgcount=1 Sep 27 16:15:55.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Sep 27 16:15:55.153 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4707 [EV]:11966: Notified SysMgr of availability (Nbrs converged) Sep 27 16:15:55.154 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000000 nbr 0x7e0557cc7000, set peer ack version to 9, afi 4, loc 2 Sep 27 16:15:55.154 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000000 nbr 0x7e0557cc8b40, set peer ack version to 9, afi 4, loc 2 Sep 27 16:15:55.156 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:15:55.156 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=15, pureRR=0 Sep 27 16:15:55.156 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[14 - 15]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Sep 27 16:15:55.156 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Sep 27 16:15:55.156 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:15:55.156 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 15 Sep 27 16:16:25.124 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Sep 27 16:16:25.124 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6748: Import walk: import ver 15 -> 16: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:16:25.124 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4706 [IMPORT]:6777: Import walk: import ver 15 -> 16: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Sep 27 16:16:25.124 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 15, min_fwdtable_version 15, import_version 16, label_version 15, table_version 16 Sep 27 16:16:25.125 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 15 -> 16, be 1, skp 0 upd 0 Sep 27 16:16:25.125 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4695 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 15 -> 16, be 0, skp 0 upd 1 Sep 27 16:16:25.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 16, min_fwdtable_version 15, import_version 16, label_version 16, table_version 16 Sep 27 16:16:25.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[15 - 16]: tblver=16, labelver=16 Sep 27 16:16:25.125 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Sep 27 16:16:25.126 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [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 Sep 27 16:16:25.126 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:17487: RIB(CUST-A:Vv4u): ver 15 -> 16 :pfx inst/upd/skip/rem 1/0/0/0 Sep 27 16:16:25.126 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[15 - 16]: minfwdtblver=16(acked=16) Sep 27 16:16:25.126 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4698 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=1, skip=0, mod=0, wdr=0}, updtrigger=1 Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=16, pureRR=0 Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[15 - 16]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=1, wdn=0, skp=0, sup=0, bestext=0} Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=16: msgcount=1 Sep 27 16:16:25.150 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=0} Sep 27 16:16:25.151 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000000 nbr 0x7e0557cc7000, set peer ack version to 15, afi 4, loc 2 Sep 27 16:16:25.151 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000000 nbr 0x7e0557cc8b40, set peer ack version to 15, afi 4, loc 2 Sep 27 16:16:25.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13186: UPDGEN_UPD: table="TBL:CUST-A (1/1)", parentafi=4, afi=0, updgrp=0.2, subgrp=0.1 Sep 27 16:16:25.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=16, pureRR=0 Sep 27 16:16:25.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13193: UPDGEN_UPD3: walkversion=[15 - 16]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Sep 27 16:16:25.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=1, adv=0, wdn=0, skp=1, sup=0, bestext=0} Sep 27 16:16:25.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Sep 27 16:16:25.153 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4707 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 16 Sep 27 16:16:40.075 bgp/bpm-tr2-apic 0/RP0/CPU0 t4615 [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:16:56.099 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000002 nbr 0x7e0557cc54c0, set peer ack version to 9, afi 0, loc 2 Sep 27 16:16:56.099 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000002 nbr 0x7e0557cc54c0, set peer ack version to 14, afi 0, loc 2 Sep 27 16:16:56.099 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:1000: vrf 0x60000002 nbr 0x7e0557cc54c0, set peer ack version to 16, afi 0, loc 3 Sep 27 16:16:56.261 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4710 [SYNC]:2280: reached initsync version 9 for afi VPNv4 Unicast, min_nbr_ack version 15, scanner_runs 2 Sep 27 16:16:56.261 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4710 [SYNC]:1792: NSR event: 'End of Convergence' on active Sep 27 16:16:56.261 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4710 [SYNC]:1792: NSR event: 'End of Convergence' on active Sep 27 16:16:56.261 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4708 [SYNC]:6368: active NSR state transition for 'Standby Sync-group', event 'End of Convergence', 'Active Ready' -> 'Active Ready', mask 0x12 Sep 27 16:16:56.263 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4708 [SYNC]:6368: active NSR state transition for 'ISSU Sync-group', event 'End of Convergence', 'None' -> 'None', mask 0x10 Sep 27 16:16:56.263 default-bgp/spkr-tr2-issu 0/RP0/CPU0 t4708 [ISSU]:541: ISM: ISSU Not enabled Sep 27 16:16:56.263 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4708 [SYNC]:6460: send active up msg to 'ISSU Sync-group' 'Unknown', after NSR restart or tcp restart Sep 27 16:16:56.263 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4708 [SYNC]:5241: scoped sync: trigger forced cleanup, batch state 0, count 0 Sep 27 16:16:56.263 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4624 [SYNC]:4950: scoped sync: forced cleanup, batch state 0 Sep 27 16:16:56.263 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4624 [SYNC]:4952: scoped sync: batch state change from 0 to 0 Sep 27 16:16:56.263 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4708 [SYNC]:8714: scoped sync: skip sending table update done, global NSR not ready Sep 27 16:17:26.099 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000000 nbr 0x7e0557cc8b40, set peer ack version to 16, afi 4, loc 2 Sep 27 16:17:26.099 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4704 [COMMON]:857: vrf 0x60000000 nbr 0x7e0557cc7000, set peer ack version to 16, afi 4, loc 2