RP/0/RP0/CPU0:PE2#show bgp trace | utility tail -n 150 Sun Oct 4 05:52:18.649 UTC Oct 4 05:38:43.518 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:38:43.518 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 4 05:38:43.520 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.1 Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=20, pureRR=0 Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[15 - 20]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=1, wdn=0, skp=1, sup=0, bestext=0} Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=20: msgcount=1 Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1} Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=0, UG=2, FG=1, walktype=0, targetver=20: msgcount=1 Oct 4 05:38:43.520 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=0, sup=0, skp=1} Oct 4 05:39:17.288 bgp/bpm-tr2-apic 0/RP0/CPU0 t4595 [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 4 05:39:48.391 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 15, afi 0, loc 2 Oct 4 05:39:48.391 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 20, afi 0, loc 2 Oct 4 05:39:48.391 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 15, afi 4, loc 2 Oct 4 05:39:48.391 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 20, afi 4, loc 3 Oct 4 05:39:48.391 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 15, afi 4, loc 2 Oct 4 05:39:48.391 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 20, afi 4, loc 3 Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4681 [SYNC]:2280: reached initsync version 14 for afi VPNv4 Unicast, min_nbr_ack version 20, scanner_runs 3 Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4681 [SYNC]:1792: NSR event: 'End of Convergence' on active Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4681 [SYNC]:1792: NSR event: 'End of Convergence' on active Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4679 [SYNC]:6368: active NSR state transition for 'Standby Sync-group', event 'End of Convergence', 'Active Ready' -> 'Active Ready', mask 0x12 Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4679 [SYNC]:6368: active NSR state transition for 'ISSU Sync-group', event 'End of Convergence', 'None' -> 'None', mask 0x10 Oct 4 05:40:46.289 default-bgp/spkr-tr2-issu 0/RP0/CPU0 t4679 [ISSU]:541: ISM: ISSU Not enabled Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4679 [SYNC]:6460: send active up msg to 'ISSU Sync-group' 'Unknown', after NSR restart or tcp restart Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4679 [SYNC]:5241: scoped sync: trigger forced cleanup, batch state 0, count 0 Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4602 [SYNC]:4950: scoped sync: forced cleanup, batch state 0 Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4602 [SYNC]:4952: scoped sync: batch state change from 0 to 0 Oct 4 05:40:46.289 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4679 [SYNC]:8714: scoped sync: skip sending table update done, global NSR not ready Oct 4 05:43:38.317 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:43:38.317 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 20 -> 23: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:43:38.317 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 20 -> 23: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:43:38.317 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 20, min_fwdtable_version 20, import_version 23, label_version 20, table_version 23 Oct 4 05:43:38.318 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 20 -> 22, be 0, skp 0 upd 1 Oct 4 05:43:38.318 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 20 -> 22, be 0, skp 0 upd 1 Oct 4 05:43:38.318 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:43:38.318 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 22 -> 23, be 0, skp 0 upd 0 Oct 4 05:43:38.318 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 22 -> 23, be 0, skp 0 upd 1 Oct 4 05:43:38.318 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 23, min_fwdtable_version 20, import_version 23, label_version 23, table_version 23 Oct 4 05:43:38.318 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[20 - 23]: tblver=23, labelver=23 Oct 4 05:43:38.318 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 05:43:38.319 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, rib_calls = 1, total_time = 1 ms, rib_api_time = 0 ms, avg_rib_api_time = 0 ms, max_rib_api_time = 0 ms Oct 4 05:43:38.319 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 20 -> 23 :pfx inst/upd/skip/rem 0/2/0/0 Oct 4 05:43:38.319 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[20 - 23]: minfwdtblver=23(acked=23) Oct 4 05:43:38.319 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=2, wdr=0}, updtrigger=1 Oct 4 05:43:38.346 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 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=23, pureRR=0 Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[20 - 23]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=1, wdn=1, skp=0, sup=0, bestext=0} Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=23: msgcount=2 Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=1, sup=0, skp=0} Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=23: msgcount=1 Oct 4 05:43:38.346 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=1, wdn=1, sup=0, skp=0} Oct 4 05:43:38.346 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4678 [EV]:11966: Notified SysMgr of availability (Nbrs converged) Oct 4 05:43:38.349 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.1 Oct 4 05:43:38.349 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=23, pureRR=0 Oct 4 05:43:38.349 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[20 - 23]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:43:38.349 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 4 05:43:38.349 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:43:38.349 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 23, afi 0, loc 4 Oct 4 05:43:38.349 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 23 Oct 4 05:43:44.922 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:43:44.922 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 23 -> 25, be 0, skp 0 upd 0 Oct 4 05:43:44.922 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 23 -> 25, be 0, skp 0 upd 1 Oct 4 05:43:44.922 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 23 -> 25: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:43:44.922 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 23 -> 25: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:43:44.922 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 25, min_fwdtable_version 23, import_version 25, label_version 25, table_version 25 Oct 4 05:43:44.922 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[23 - 25]: tblver=25, labelver=25 Oct 4 05:43:44.922 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 05:43:44.924 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, 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 4 05:43:44.924 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 23 -> 25 :pfx inst/upd/skip/rem 0/2/0/0 Oct 4 05:43:44.924 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[23 - 25]: minfwdtblver=25(acked=25) Oct 4 05:43:44.924 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=2, wdr=0}, updtrigger=1 Oct 4 05:43:44.951 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 4 05:43:44.951 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=25, pureRR=0 Oct 4 05:43:44.951 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[23 - 25]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:43:44.951 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 4 05:43:44.951 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:43:44.951 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 25 Oct 4 05:43:44.953 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.1 Oct 4 05:43:44.953 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=25, pureRR=0 Oct 4 05:43:44.953 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[23 - 25]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:43:44.953 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 4 05:43:44.953 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:43:44.953 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 25, afi 0, loc 4 Oct 4 05:43:44.953 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 25 Oct 4 05:44:48.402 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 23, afi 4, loc 2 Oct 4 05:44:48.402 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 23, afi 4, loc 2 Oct 4 05:44:48.402 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 25, afi 4, loc 3 Oct 4 05:44:48.402 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 23, afi 4, loc 2 Oct 4 05:44:48.402 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 23, afi 4, loc 2 Oct 4 05:44:48.402 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 25, afi 4, loc 3 Oct 4 05:49:07.729 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:49:07.729 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 25 -> 27: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:49:07.729 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 25 -> 27: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:49:07.729 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 25, min_fwdtable_version 25, import_version 27, label_version 25, table_version 27 Oct 4 05:49:07.730 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 25 -> 27, be 1, skp 0 upd 0 Oct 4 05:49:07.730 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 25 -> 27, be 0, skp 0 upd 1 Oct 4 05:49:07.730 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 27, min_fwdtable_version 25, import_version 27, label_version 27, table_version 27 Oct 4 05:49:07.730 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[25 - 27]: tblver=27, labelver=27 Oct 4 05:49:07.730 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 05:49:07.731 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17459: RIB :time_rib_batch tot_pfx = 2, 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 4 05:49:07.731 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 25 -> 27 :pfx inst/upd/skip/rem 0/2/0/0 Oct 4 05:49:07.731 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[25 - 27]: minfwdtblver=27(acked=27) Oct 4 05:49:07.731 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=2, wdr=0}, updtrigger=1 Oct 4 05:49:07.757 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 4 05:49:07.757 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=27, pureRR=0 Oct 4 05:49:07.757 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[25 - 27]: resumedver=0, advdisabled=0, completed=1, msgcount=1 Oct 4 05:49:07.757 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=2, wdn=0, skp=0, sup=0, bestext=0} Oct 4 05:49:07.757 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:49:07.757 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=27: msgcount=1 Oct 4 05:49:07.757 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=0} Oct 4 05:49:07.760 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.1 Oct 4 05:49:07.760 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=27, pureRR=0 Oct 4 05:49:07.760 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[25 - 27]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:49:07.760 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 4 05:49:07.760 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:49:07.760 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 27, afi 0, loc 4 Oct 4 05:49:07.760 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 27 Oct 4 05:49:07.797 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 4 05:49:07.797 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 27 -> 28, be 0, skp 0 upd 0 Oct 4 05:49:07.797 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4664 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 27 -> 28, be 0, skp 0 upd 1 Oct 4 05:49:07.797 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6748: Import walk: import ver 27 -> 28: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:49:07.797 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 27, min_fwdtable_version 27, import_version 27, label_version 28, table_version 28 Oct 4 05:49:07.797 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4677 [IMPORT]:6777: Import walk: import ver 27 -> 28: 1 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 4 05:49:07.798 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [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 4 05:49:07.798 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[27 - 28]: tblver=28, labelver=28 Oct 4 05:49:07.798 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 3, last_recv_version 3 Oct 4 05:49:07.799 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [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 4 05:49:07.799 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:17487: RIB(CUST-A:Vv4u): ver 27 -> 28 :pfx inst/upd/skip/rem 0/1/0/0 Oct 4 05:49:07.799 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[27 - 28]: minfwdtblver=28(acked=28) Oct 4 05:49:07.799 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4667 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=0}, updtrigger=1 Oct 4 05:49:07.826 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.1 Oct 4 05:49:07.826 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0xe0000002, subgrp=0.1, walktype=0(Regular), inputtargetver=28, pureRR=0 Oct 4 05:49:07.826 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[27 - 28]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:49:07.826 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Oct 4 05:49:07.826 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:49:07.826 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4678 [COMMON]:3717: vrf 0x60000002 nbr 0x7631d02b64c0, set peer ack version to 28, afi 0, loc 4 Oct 4 05:49:07.826 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:CUST-A (1/1)) subgrp:(1) UG:(2) end 28 Oct 4 05:49:07.828 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 4 05:49:07.828 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=28, pureRR=0 Oct 4 05:49:07.828 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13193: UPDGEN_UPD3: walkversion=[27 - 28]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 4 05:49:07.828 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Oct 4 05:49:07.828 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4678 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 4 05:49:07.828 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 28 Oct 4 05:50:18.410 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 27, afi 4, loc 2 Oct 4 05:50:18.410 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7631d02b8000, set peer ack version to 28, afi 4, loc 3 Oct 4 05:50:18.410 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:857: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 27, afi 4, loc 2 Oct 4 05:50:18.410 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4675 [COMMON]:1000: vrf 0x60000000 nbr 0x7631d02b9b40, set peer ack version to 28, afi 4, loc 3