RP/0/RP0/CPU0:PE3#show bgp trace | utility tail -n 150 Mon Oct 12 05:14:05.752 UTC Oct 12 04:51:46.647 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[15 - 18]: minfwdtblver=18(acked=18) Oct 12 04:51:46.647 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=0}, updtrigger=1 Oct 12 04:51:46.647 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 20, min_fwdtable_version 18, import_version 20, label_version 20, table_version 20 Oct 12 04:51:46.647 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[18 - 20]: tblver=20, labelver=20 Oct 12 04:51:46.647 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 04:51:46.648 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [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 Oct 12 04:51:46.648 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:17487: RIB(CUST-A:Vv4u): ver 18 -> 20 :pfx inst/upd/skip/rem 0/2/0/0 Oct 12 04:51:46.648 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[18 - 20]: minfwdtblver=20(acked=20) Oct 12 04:51:46.648 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=2, wdr=0}, updtrigger=1 Oct 12 04:51:46.670 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 04:51:46.670 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=20, pureRR=0 Oct 12 04:51:46.670 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13193: UPDGEN_UPD3: walkversion=[15 - 20]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 04:51:46.670 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=0, wdn=0, skp=2, sup=0, bestext=0} Oct 12 04:51:46.670 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 04:51:46.670 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 20 Oct 12 04:52:28.508 bgp/bpm-tr2-apic 0/RP0/CPU0 t4656 [AIPC]:210: bpm txlist show:loc 3 [1:txlist-handler, 2:conn-up 3:timer-cleanup] active-mem 1, suspend-mem 0, end-list 1, remove-mem 0, elements 0 Oct 12 04:52:59.527 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:857: vrf 0x60000000 nbr 0x7ff0ce997000, set peer ack version to 15, afi 4, loc 2 Oct 12 04:52:59.527 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:1000: vrf 0x60000000 nbr 0x7ff0ce997000, set peer ack version to 20, afi 4, loc 3 Oct 12 04:52:59.527 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:857: vrf 0x60000000 nbr 0x7ff0ce998b40, set peer ack version to 15, afi 4, loc 2 Oct 12 04:52:59.527 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:1000: vrf 0x60000000 nbr 0x7ff0ce998b40, set peer ack version to 20, afi 4, loc 3 Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4758 [SYNC]:2280: reached initsync version 13 for afi VPNv4 Unicast, min_nbr_ack version 20, scanner_runs 3 Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4758 [SYNC]:1792: NSR event: 'End of Convergence' on active Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4758 [SYNC]:1792: NSR event: 'End of Convergence' on active Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4756 [SYNC]:6368: active NSR state transition for 'Standby Sync-group', event 'End of Convergence', 'Active Ready' -> 'Active Ready', mask 0x12 Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4756 [SYNC]:6368: active NSR state transition for 'ISSU Sync-group', event 'End of Convergence', 'None' -> 'None', mask 0x10 Oct 12 04:53:49.438 default-bgp/spkr-tr2-issu 0/RP0/CPU0 t4756 [ISSU]:541: ISM: ISSU Not enabled Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4756 [SYNC]:6460: send active up msg to 'ISSU Sync-group' 'Unknown', after NSR restart or tcp restart Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4756 [SYNC]:5241: scoped sync: trigger forced cleanup, batch state 0, count 0 Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4665 [SYNC]:4950: scoped sync: forced cleanup, batch state 0 Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4665 [SYNC]:4952: scoped sync: batch state change from 0 to 0 Oct 12 04:53:49.438 default-bgp/spkr-tr2-sync 0/RP0/CPU0 t4756 [SYNC]:8714: scoped sync: skip sending table update done, global NSR not ready Oct 12 04:57:47.386 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 04:57:47.386 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 20 -> 22, be 0, skp 0 upd 0 Oct 12 04:57:47.386 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 20 -> 22, be 0, skp 0 upd 1 Oct 12 04:57:47.386 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4754 [IMPORT]:6748: Import walk: import ver 20 -> 22: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 04:57:47.386 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4754 [IMPORT]:6777: Import walk: import ver 20 -> 22: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 04:57:47.386 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 22, min_fwdtable_version 20, import_version 22, label_version 22, table_version 22 Oct 12 04:57:47.386 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[20 - 22]: tblver=22, labelver=22 Oct 12 04:57:47.386 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 04:57:47.387 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [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 Oct 12 04:57:47.387 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:17487: RIB(CUST-A:Vv4u): ver 20 -> 22 :pfx inst/upd/skip/rem 0/1/0/1 Oct 12 04:57:47.387 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[20 - 22]: minfwdtblver=22(acked=22) Oct 12 04:57:47.387 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=1}, updtrigger=1 Oct 12 04:57:47.410 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 04:57:47.410 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=22, pureRR=0 Oct 12 04:57:47.410 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13193: UPDGEN_UPD3: walkversion=[20 - 22]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 04:57:47.410 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Oct 12 04:57:47.410 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 04:57:47.410 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4755 [COMMON]:3717: vrf 0x60000000 nbr 0x7ff0ce998b40, set peer ack version to 22, afi 4, loc 4 Oct 12 04:57:47.410 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4755 [COMMON]:3717: vrf 0x60000000 nbr 0x7ff0ce997000, set peer ack version to 22, afi 4, loc 4 Oct 12 04:57:47.411 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 22 Oct 12 05:03:46.585 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 05:03:46.585 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 22 -> 24, be 0, skp 0 upd 0 Oct 12 05:03:46.585 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 22 -> 24, be 0, skp 0 upd 1 Oct 12 05:03:46.585 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4754 [IMPORT]:6748: Import walk: import ver 22 -> 24: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 05:03:46.585 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4754 [IMPORT]:6777: Import walk: import ver 22 -> 24: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 05:03:46.586 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 24, min_fwdtable_version 22, import_version 24, label_version 24, table_version 24 Oct 12 05:03:46.586 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[22 - 24]: tblver=24, labelver=24 Oct 12 05:03:46.586 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 05:03:46.586 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [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 Oct 12 05:03:46.586 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:17487: RIB(CUST-A:Vv4u): ver 22 -> 24 :pfx inst/upd/skip/rem 0/1/0/1 Oct 12 05:03:46.587 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[22 - 24]: minfwdtblver=24(acked=24) Oct 12 05:03:46.587 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=1}, updtrigger=1 Oct 12 05:03:46.612 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 05:03:46.612 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=24, pureRR=0 Oct 12 05:03:46.612 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13193: UPDGEN_UPD3: walkversion=[22 - 24]: resumedver=0, advdisabled=0, completed=1, msgcount=0 Oct 12 05:03:46.612 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=0, adv=0, wdn=0, skp=0, sup=0, bestext=0} Oct 12 05:03:46.612 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 05:03:46.612 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4755 [COMMON]:3717: vrf 0x60000000 nbr 0x7ff0ce998b40, set peer ack version to 24, afi 4, loc 4 Oct 12 05:03:46.612 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4755 [COMMON]:3717: vrf 0x60000000 nbr 0x7ff0ce997000, set peer ack version to 24, afi 4, loc 4 Oct 12 05:03:46.612 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13360: Updgen - Skip EoR for Tbl:(TBL:default (1/128)) subgrp:(1) UG:(2) end 24 Oct 12 05:09:48.535 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:11792: Rx-ed 2 redist routes, 0 firsthop notifs, 0 delete Oct 12 05:09:48.535 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:4366: Config release label mode for table 0xe0000002 (mode:6, 6, 6) flags:0x201, 0x0 Oct 12 05:09:48.535 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:9001: label walk afi:VPNv4 Unicast, lbl ver 24 -> 26, be 0, skp 0 upd 0 Oct 12 05:09:48.535 default-bgp/spkr-tr2-label 0/RP0/CPU0 t4741 [LABEL]:9006: label walk afi:VPNv4 Unicast, lbl ver 24 -> 26, be 0, skp 0 upd 1 Oct 12 05:09:48.535 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4754 [IMPORT]:6748: Import walk: import ver 24 -> 26: 0 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 05:09:48.535 default-bgp/spkr-tr2-imp 0/RP0/CPU0 t4754 [IMPORT]:6777: Import walk: import ver 24 -> 26: 2 nets processed (0 paths updated) afi 4 forced/ms 0 Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18929: RIB walk for afi VPNv4 Unicast: target version 26, min_fwdtable_version 24, import_version 26, label_version 26, table_version 26 Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:18965: RIBUPD_START: parentafi=4{rtrstate=6}, walkversion=[24 - 26]: tblver=26, labelver=26 Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:16216: send converge to RIB, afi VPNv4 Unicast, tableid 0xe0000002, converged 1, send_version 2, last_recv_version 2 Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [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 Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:17487: RIB(CUST-A:Vv4u): ver 24 -> 26 :pfx inst/upd/skip/rem 0/1/0/1 Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19031: RIBUPD_END: parentafi=4{rtrstate=6}, walkversion=[24 - 26]: minfwdtblver=26(acked=26) Oct 12 05:09:48.536 default-bgp/spkr-tr2-rib 0/RP0/CPU0 t4746 [RIB]:19038: RIBUPD_END2: parentafi=4, prefixstats={install=0, skip=0, mod=1, wdr=1}, updtrigger=1 Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.1 Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.1, walktype=0(Regular), inputtargetver=26, pureRR=0 Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13193: UPDGEN_UPD3: walkversion=[24 - 26]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=2, adv=2, wdn=0, skp=0, sup=0, bestext=0} Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=26: msgcount=2 Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=0} Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=26: msgcount=1 Oct 12 05:09:48.591 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=0} Oct 12 05:09:48.591 default-bgp/spkr-tr2-ev 0/RP0/CPU0 t4755 [EV]:11966: Notified SysMgr of availability (Nbrs converged) Oct 12 05:09:54.497 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4676 [POSTIT]:5163: skip postit: 'Outbound user soft clear', nbr: 1.1.1.1, reason: 1 Oct 12 05:09:54.497 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4758 [POSTIT]:5163: skip postit: 'None', nbr: 1.1.1.1, reason: 1 Oct 12 05:09:54.497 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:957: Updgen - Resending table to neighbor 1.1.1.1, afi VPNv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Oct 12 05:09:56.243 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4758 [COMMON]:2504: nbr soft-reconfig 'ptr: 0x7ff0ce998b40',afi: 4 af_flag: 0x011100a4 af3_flag: 0x00080000 loc: 1 Oct 12 05:09:56.243 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4758 [COMMON]:2571: soft-reconfig delayed bestpath: elapsed time(ms) 0, total run 0 , afi VPNv4 Unicast, count 1 Oct 12 05:09:56.498 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4758 [POSTIT]:7502: skip pending postit: nbr: 1.1.1.1, afi: 4, global nsr not ready Oct 12 05:09:56.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:VPNv4 Unicast:Nbr:1.1.1.1) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 05:09:56.498 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4758 [COMMON]:6095: nbr_ptr 0x7ff0ce998b40 resend updates, afi VPNv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 05:09:56.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(1.1.1.1) fgrp idx 0.1 subgrp idx 0.1 updgrp 0.2 rtset 0 Oct 12 05:09:56.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:56.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2792: Filter-group op (Filter-group Add Nbr new) Tbl/Nbr(Afi:VPNv4 Unicast:Vrf:default:Nbr:1.1.1.1) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 05:09:56.498 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2818: created filtergrp 2 for vrf 0x60000000 nbr 1.1.1.1, afi 4, subgrp version 0, refresh 0 Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.2 Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.2, walktype=0(Regular), inputtargetver=26, pureRR=0 Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 26]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=5, adv=2, wdn=0, skp=3, sup=0, bestext=0} Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=2, walktype=0, targetver=26: msgcount=2 Oct 12 05:09:56.528 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=3} Oct 12 05:09:56.529 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:857: vrf 0x60000000 nbr 0x7ff0ce998b40, set peer ack version to 26, afi 4, loc 2 Oct 12 05:09:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:1062: Update gen release-index bit operation TBL:default (1/128) fgrp idx/size 0 Oct 12 05:09:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:1079: Update gen release-index for bit operation TBL:default (1/128) fgrp idx/size 1 Oct 12 05:09:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:3392: TBL:default (1/128) delete subgrp UG/SG:2/1 subgrp:0x7ff080004b20 caller 0x59602c956576 Oct 12 05:09:57.907 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4676 [POSTIT]:5163: skip postit: 'Outbound user soft clear', nbr: 2.2.2.2, reason: 1 Oct 12 05:09:57.907 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4758 [POSTIT]:5163: skip postit: 'None', nbr: 2.2.2.2, reason: 1 Oct 12 05:09:57.907 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:957: Updgen - Resending table to neighbor 2.2.2.2, afi VPNv4 Unicastrt-size 0, parallel refresh 0, route-refresh 0 Oct 12 05:09:59.628 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4758 [COMMON]:2504: nbr soft-reconfig 'ptr: 0x7ff0ce997000',afi: 4 af_flag: 0x011100a4 af3_flag: 0x00080000 loc: 1 Oct 12 05:09:59.628 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4758 [COMMON]:2571: soft-reconfig delayed bestpath: elapsed time(ms) 0, total run 0 , afi VPNv4 Unicast, count 1 Oct 12 05:09:59.907 default-bgp/spkr-tr2-postit 0/RP0/CPU0 t4758 [POSTIT]:7502: skip pending postit: nbr: 2.2.2.2, afi: 4, global nsr not ready Oct 12 05:09:59.907 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2102: Filter-group op (Filter-group Rm Nbr) Tbl/Nbr(Afi:VPNv4 Unicast:Nbr:2.2.2.2) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 05:09:59.907 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4758 [COMMON]:6095: nbr_ptr 0x7ff0ce997000 resend updates, afi VPNv4 Unicast, versions: 0, 0 fl:0x4 Oct 12 05:09:59.907 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2244: Filter-group op (Filter-group Compute Nbr RT) Tbl/Nbr(2.2.2.2) fgrp idx 0.2 subgrp idx 0.2 updgrp 0.2 rtset 0 Oct 12 05:09:59.907 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:59.907 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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.1 subgrp idx 0.3 updgrp 0.2 rtset 0 Oct 12 05:09:59.907 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:2818: created filtergrp 1 for vrf 0x60000000 nbr 2.2.2.2, afi 4, subgrp version 0, refresh 0 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13186: UPDGEN_UPD: table="TBL:default (1/128)", parentafi=4, afi=4, updgrp=0.2, subgrp=0.3 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13190: UPDGEN_UPD2: table=0x00000000, subgrp=0.3, walktype=0(Regular), inputtargetver=26, pureRR=0 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13193: UPDGEN_UPD3: walkversion=[0 - 26]: resumedver=0, advdisabled=0, completed=1, msgcount=2 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13195: UPDGEN_UPD4: prefixstats={total=5, adv=2, wdn=0, skp=3, sup=0, bestext=0} Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:13197: UPDGEN_UPD5: prefixstats={fg_wdn=0, fg_skp=0} Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4101: UPDGEN_FG_APPEND: AFI=4, UG=2, FG=1, walktype=0, targetver=26: msgcount=2 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:4104: UPDGEN_FG_APPEND2: fg-prefixstats={adv=2, wdn=0, sup=0, skp=3} Oct 12 05:09:59.933 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:857: vrf 0x60000000 nbr 0x7ff0ce997000, set peer ack version to 26, afi 4, loc 2 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [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 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:1062: Update gen release-index bit operation TBL:default (1/128) fgrp idx/size 0 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:1079: Update gen release-index for bit operation TBL:default (1/128) fgrp idx/size 2 Oct 12 05:09:59.933 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4758 [UPD]:3392: TBL:default (1/128) delete subgrp UG/SG:2/2 subgrp:0x7ff06c016ba0 caller 0x59602c956576 Oct 12 05:10:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:3184: TBL:default (1/128) free subgrp SG:2 subgrp:0x7ff06c016ba0 Oct 12 05:10:56.529 default-bgp/spkr-tr2-upd 0/RP0/CPU0 t4755 [UPD]:3184: TBL:default (1/128) free subgrp SG:1 subgrp:0x7ff080004b20 Oct 12 05:10:59.555 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:857: vrf 0x60000000 nbr 0x7ff0ce998b40, set peer ack version to 26, afi 4, loc 2 Oct 12 05:11:14.555 default-bgp/spkr-tr2-common 0/RP0/CPU0 t4752 [COMMON]:857: vrf 0x60000000 nbr 0x7ff0ce997000, set peer ack version to 26, afi 4, loc 2