Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

bgpd: flapped quicker than processing #14895

Closed
louis-6wind opened this issue Nov 28, 2023 · 2 comments
Closed

bgpd: flapped quicker than processing #14895

louis-6wind opened this issue Nov 28, 2023 · 2 comments
Labels
triage Needs further investigation

Comments

@louis-6wind
Copy link
Contributor

Describe the bug

Migration to exabgp 4 shows a bug in bgpd when running the bgp_peer_type_multipath_relax topotests.
After setting neighbor 10.0.4.2 ebgp-multihop with exabgp 4, the BGP session is re-established, and the test breaks, which was not the case with exabgp 3.
Debug shows that exabgp 4 re-establishes the BGP session quicker than exabgp 3.

When failed, the 10.0.4.2 path to 203.0.113.8/30 is incorrect and the message appears:

2023/11/28 15:25:56.181100 BGP: [PMW16-C134F] 10.0.4.2(Unknown) rcvd 203.0.113.8/30 IPv4 unicast, flapped quicker than processing

Change a exabgp parameter to make it slower workarounds the issue.

  • [X ] Did you check if this is a duplicate issue?
  • [X ] Did you test it on the latest FRRouting/frr master branch?

To Reproduce

  1. Clone https://github.com/louis-6wind/frr/tree/exabgp4
  2. Uninstall exabgp 3: python2 -m pip uninstall exabgp
  3. Install exabgp 3: python3 -m pip install exabgp==4.2.21
  4. Enable debug
diff --git a/tests/topotests/bgp_peer_type_multipath_relax/r1/bgpd.conf b/tests/topotests/bgp_peer_type_multipath_relax/r1/bgpd.conf
index 038f108aa8..9e893aac35 100644
--- a/tests/topotests/bgp_peer_type_multipath_relax/r1/bgpd.conf
+++ b/tests/topotests/bgp_peer_type_multipath_relax/r1/bgpd.conf
@@ -1,3 +1,6 @@
+debug bgp bestpath 203.0.113.8/30
+debug bgp updates in
+debug bgp updates out
 !
 router bgp 64510
  bgp router-id 10.0.1.1
  1. Run bgp_peer_type_multipath_relax topotests
  2. See error
  3. Make exabgp slower with the following patch and restart the test. It passes.
diff --git a/tests/topotests/bgp_peer_type_multipath_relax/exabgp.env b/tests/topotests/bgp_peer_type_multipath_relax/exabgp.env
index bb36af522a..989228a293 100644
--- a/tests/topotests/bgp_peer_type_multipath_relax/exabgp.env
+++ b/tests/topotests/bgp_peer_type_multipath_relax/exabgp.env
@@ -44,7 +44,7 @@ enable = false
 file = ''
 
 [exabgp.reactor]
-speed = 1.0
+speed = 5.0
 
 [exabgp.tcp]
 acl = false

Expected behavior
Test is passing

Screenshots

Exabgp 4: NOK

2023/11/28 15:25:55.714824 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:55.715171 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.0/30 json
2023/11/28 15:25:55.846991 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:55.847544 BGP: [M7Q4P-46WDR] vty[30]@# do show logging
2023/11/28 15:25:55.946086 BGP: [WJC21-WPGZF] u3:s3 announcing routes to 10.0.5.2, combined into 1 peers
2023/11/28 15:25:55.946099 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=198.51.100.0/24, selected=0x60d0000324a0
2023/11/28 15:25:55.946143 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032710
2023/11/28 15:25:55.946151 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.4/30, selected=0x60d000032a50
2023/11/28 15:25:55.946155 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.8/30, selected=0x60d000032980
2023/11/28 15:25:55.946221 BGP: [TN0HX-6G1RR] u3:s3 send UPDATE w/ attr: nexthop 10.0.3.2, origin i, localpref 100, path 64502
2023/11/28 15:25:55.946225 BGP: [HVRWP-5R9NQ] u3:s3 send UPDATE 198.51.100.0/24 IPv4 unicast
2023/11/28 15:25:55.946230 BGP: [HVRWP-5R9NQ] u3:s3 send UPDATE 203.0.113.8/30 IPv4 unicast
2023/11/28 15:25:55.946235 BGP: [WEV7K-2GAQ5] u3:s3 send UPDATE len 66 (max message len: 65535) numpfx 2
2023/11/28 15:25:55.946243 BGP: [MBFVT-8GSC6] u3:s3 10.0.5.2 send UPDATE w/ nexthop 10.0.3.2
2023/11/28 15:25:55.946254 BGP: [TN0HX-6G1RR] u3:s3 send UPDATE w/ attr: nexthop 198.51.100.2, origin i, localpref 100, path (64511) 64499
2023/11/28 15:25:55.946256 BGP: [HVRWP-5R9NQ] u3:s3 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:25:55.946258 BGP: [HVRWP-5R9NQ] u3:s3 send UPDATE 203.0.113.4/30 IPv4 unicast
2023/11/28 15:25:55.946261 BGP: [WEV7K-2GAQ5] u3:s3 send UPDATE len 71 (max message len: 65535) numpfx 2
2023/11/28 15:25:55.946263 BGP: [MBFVT-8GSC6] u3:s3 10.0.5.2 send UPDATE w/ nexthop 198.51.100.2
2023/11/28 15:25:55.946353 BGP: [T5AAP-5GA85] 10.0.5.2(r2) rcvd UPDATE w/ attr: nexthop 198.51.100.2, origin i, localpref 100, path (64511) 64499
2023/11/28 15:25:55.946356 BGP: [PCFFM-WMARW] 10.0.5.2(r2) rcvd UPDATE wlen 0 attrlen 34 alen 10
2023/11/28 15:25:55.946370 BGP: [NJYMB-R5FFH] 10.0.5.2(r2) rcvd 203.0.113.0/30 IPv4 unicast...duplicate ignored
2023/11/28 15:25:55.946374 BGP: [NJYMB-R5FFH] 10.0.5.2(r2) rcvd 203.0.113.4/30 IPv4 unicast...duplicate ignored
2023/11/28 15:25:56.046727 BGP: [T5AAP-5GA85] 10.0.5.2(r2) rcvd UPDATE w/ attr: nexthop 198.51.100.2, origin i, localpref 100, path (64511) 64499
2023/11/28 15:25:56.046732 BGP: [PCFFM-WMARW] 10.0.5.2(r2) rcvd UPDATE wlen 0 attrlen 34 alen 10
2023/11/28 15:25:56.046755 BGP: [NJYMB-R5FFH] 10.0.5.2(r2) rcvd 203.0.113.0/30 IPv4 unicast...duplicate ignored
2023/11/28 15:25:56.046761 BGP: [NJYMB-R5FFH] 10.0.5.2(r2) rcvd 203.0.113.4/30 IPv4 unicast...duplicate ignored
2023/11/28 15:25:56.112609 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:56.113146 BGP: [M7Q4P-46WDR] vty[30]@# conf
2023/11/28 15:25:56.113342 BGP: [M7Q4P-46WDR] vty[30]@(config)#  router bgp 64510
2023/11/28 15:25:56.124042 BGP: [M7Q4P-46WDR] vty[30]@(config-router)#  neighbor 10.0.4.2 ebgp-multihop
2023/11/28 15:25:56.181052 BGP: [T5AAP-5GA85] 10.0.4.2(Unknown) rcvd UPDATE w/ attr: nexthop 198.51.100.10, origin i, path 64503
2023/11/28 15:25:56.181057 BGP: [PCFFM-WMARW] 10.0.4.2(Unknown) rcvd UPDATE wlen 0 attrlen 20 alen 10
2023/11/28 15:25:56.181082 BGP: [PMW16-C134F] 10.0.4.2(Unknown) rcvd 203.0.113.0/30 IPv4 unicast, flapped quicker than processing
2023/11/28 15:25:56.181086 BGP: [YCKEM-GB33T] 10.0.4.2(Unknown) rcvd 203.0.113.0/30 IPv4 unicast
2023/11/28 15:25:56.181100 BGP: [PMW16-C134F] 10.0.4.2(Unknown) rcvd 203.0.113.8/30 IPv4 unicast, flapped quicker than processing
2023/11/28 15:25:56.181101 BGP: [YCKEM-GB33T] 10.0.4.2(Unknown) rcvd 203.0.113.8/30 IPv4 unicast
2023/11/28 15:25:56.181114 BGP: [PCFFM-WMARW] 10.0.4.2(Unknown) rcvd UPDATE wlen 0 attrlen 0 alen 0
2023/11/28 15:25:56.181117 BGP: [G3V0W-FSWRC] bgp_best_path_select_defer: processing route for IPv4 Unicast : cnt 0
2023/11/28 15:25:56.181133 BGP: [M59KS-A3ZXZ] bgp_update_receive: rcvd End-of-RIB for IPv4 Unicast from 10.0.4.2 in vrf default
2023/11/28 15:25:56.184677 BGP: [V7N4G-NR80B] bgp_process_main_one: p=203.0.113.8/30(VRF default) afi=IPv4, safi=unicast start
2023/11/28 15:25:56.184686 BGP: [KQ9MH-8EWT4] 203.0.113.8/30(VRF default): path 10.0.3.2 is the initial bestpath
2023/11/28 15:25:56.184687 BGP: [N0SA5-2FRH7] bgp_best_selection: 203.0.113.8/30(VRF default) pi from 10.0.4.2 in holddown
2023/11/28 15:25:56.184689 BGP: [N6CTF-2RSKS] 203.0.113.8/30(VRF default): After path selection, newbest is path 10.0.3.2 oldbest was 10.0.3.2
2023/11/28 15:25:56.184691 BGP: [ZF63D-VT50R] 203.0.113.8/30(VRF default): path 10.0.3.2 is the bestpath, add to the multipath list
2023/11/28 15:25:56.184693 BGP: [VMWTX-J3P3K] 203.0.113.8/30(VRF default): starting mpath update, newbest 10.0.3.2 num candidates 1 old-mpath-count 0 old-cum-bw 0
2023/11/28 15:25:56.184696 BGP: [YW4BC-Y5TG6] 203.0.113.8/30(VRF default): comparing candidate 10.0.3.2 with existing mpath NONE
2023/11/28 15:25:56.184697 BGP: [S7KWG-REZFR] 203.0.113.8/30(VRF default): New mpath count (incl newbest) 1 mpath-change NO all_paths_lb 0 cum_bw 0
2023/11/28 15:25:56.184699 BGP: [GVV1N-MH1P0] bgp_process_main_one: p=203.0.113.8/30(VRF default) afi=IPv4, safi=unicast, old_select=0x60d000032980, new_select=0x60d000032980
2023/11/28 15:25:56.257445 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:56.257877 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:25:57.385576 BGP: [K9G3A-CJ5QQ] u2:s4 announcing routes upon coalesce timer expiry(1250 ms)
2023/11/28 15:25:57.385589 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=198.51.100.0/24, selected=0x60d0000324a0
2023/11/28 15:25:57.385633 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032710
2023/11/28 15:25:57.385641 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.4/30, selected=0x60d000032a50
2023/11/28 15:25:57.385645 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.8/30, selected=0x60d000032980
2023/11/28 15:25:57.385719 BGP: [TN0HX-6G1RR] u2:s4 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, path 64502
2023/11/28 15:25:57.385722 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 198.51.100.0/24 IPv4 unicast
2023/11/28 15:25:57.385728 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 203.0.113.8/30 IPv4 unicast
2023/11/28 15:25:57.385734 BGP: [WEV7K-2GAQ5] u2:s4 send UPDATE len 57 (max message len: 65535) numpfx 2
2023/11/28 15:25:57.385739 BGP: [MBFVT-8GSC6] u2:s4 10.0.4.2 send UPDATE w/ nexthop 10.0.4.1
2023/11/28 15:25:57.385751 BGP: [TN0HX-6G1RR] u2:s4 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, localpref 100, path (64511) 64499
2023/11/28 15:25:57.385752 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:25:57.385755 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 203.0.113.4/30 IPv4 unicast
2023/11/28 15:25:57.385757 BGP: [WEV7K-2GAQ5] u2:s4 send UPDATE len 58 (max message len: 65535) numpfx 2
2023/11/28 15:25:57.385759 BGP: [MBFVT-8GSC6] u2:s4 10.0.4.2 send UPDATE w/ nexthop 10.0.4.1
2023/11/28 15:25:57.390078 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:57.390481 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:25:58.523331 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:58.523806 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:25:59.650167 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:25:59.650553 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:26:00.779578 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:26:00.780041 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:26:01.910436 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:26:01.910857 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:26:03.044168 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:26:03.044590 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:26:04.179176 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:26:04.179597 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:26:05.312623 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:26:05.312996 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:26:06.461331 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:26:06.461773 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json

Exabgp 3: OK

2023/11/28 15:24:05.513515 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.0/30 json
2023/11/28 15:24:06.406253 BGP: [T5AAP-5GA85] 10.0.4.2(Unknown) rcvd UPDATE w/ attr: nexthop 198.51.100.10, origin i, path 64503
2023/11/28 15:24:06.406270 BGP: [PCFFM-WMARW] 10.0.4.2(Unknown) rcvd UPDATE wlen 0 attrlen 20 alen 5
2023/11/28 15:24:06.406346 BGP: [YCKEM-GB33T] 10.0.4.2(Unknown) rcvd 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:06.456592 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.0/30
2023/11/28 15:24:06.456627 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032710
2023/11/28 15:24:06.456712 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.0/30
2023/11/28 15:24:06.456718 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032710
2023/11/28 15:24:06.456754 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.0/30
2023/11/28 15:24:06.456759 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032710
2023/11/28 15:24:06.457182 BGP: [TN0HX-6G1RR] u1:s1 send UPDATE w/ attr: nexthop 198.51.100.2, origin i, localpref 100, path (64511) 64499
2023/11/28 15:24:06.457205 BGP: [HVRWP-5R9NQ] u1:s1 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:06.457245 BGP: [WEV7K-2GAQ5] u1:s1 send UPDATE len 66 (max message len: 65535) numpfx 1
2023/11/28 15:24:06.457266 BGP: [MBFVT-8GSC6] u1:s1 10.0.5.2 send UPDATE w/ nexthop 198.51.100.2
2023/11/28 15:24:06.457355 BGP: [TN0HX-6G1RR] u2:s2 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, localpref 100, path (64511) 64499
2023/11/28 15:24:06.457364 BGP: [HVRWP-5R9NQ] u2:s2 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:06.457382 BGP: [WEV7K-2GAQ5] u2:s2 send UPDATE len 53 (max message len: 4096) numpfx 1
2023/11/28 15:24:06.457392 BGP: [MBFVT-8GSC6] u2:s2 10.0.3.2 send UPDATE w/ nexthop 10.0.3.1
2023/11/28 15:24:06.457412 BGP: [MBFVT-8GSC6] u2:s2 10.0.4.2 send UPDATE w/ nexthop 10.0.4.1
2023/11/28 15:24:06.457441 BGP: [TN0HX-6G1RR] u3:s3 send UPDATE w/ attr: nexthop 198.51.100.2, origin i, localpref 100, path (64511) 64499
2023/11/28 15:24:06.457448 BGP: [HVRWP-5R9NQ] u3:s3 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:06.457463 BGP: [WEV7K-2GAQ5] u3:s3 send UPDATE len 62 (max message len: 4096) numpfx 1
2023/11/28 15:24:06.457469 BGP: [MBFVT-8GSC6] u3:s3 10.0.1.2 send UPDATE w/ nexthop 198.51.100.2
2023/11/28 15:24:06.665149 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:24:06.665636 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.0/30 json
2023/11/28 15:24:06.793070 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:24:06.793643 BGP: [M7Q4P-46WDR] vty[30]@# do show logging
2023/11/28 15:24:07.061088 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:24:07.061660 BGP: [M7Q4P-46WDR] vty[30]@# conf
2023/11/28 15:24:07.061896 BGP: [M7Q4P-46WDR] vty[30]@(config)#  router bgp 64510
2023/11/28 15:24:07.071999 BGP: [M7Q4P-46WDR] vty[30]@(config-router)#  neighbor 10.0.4.2 ebgp-multihop 10
2023/11/28 15:24:07.132639 BGP: [V7N4G-NR80B] bgp_process_main_one: p=203.0.113.8/30(VRF default) afi=IPv4, safi=unicast start
2023/11/28 15:24:07.132649 BGP: [N0SA5-2FRH7] bgp_best_selection: 203.0.113.8/30(VRF default) pi from 10.0.4.2 in holddown
2023/11/28 15:24:07.132681 BGP: [KQ9MH-8EWT4] 203.0.113.8/30(VRF default): path 10.0.3.2 is the initial bestpath
2023/11/28 15:24:07.132683 BGP: [N6CTF-2RSKS] 203.0.113.8/30(VRF default): After path selection, newbest is path 10.0.3.2 oldbest was 10.0.3.2
2023/11/28 15:24:07.132684 BGP: [ZF63D-VT50R] 203.0.113.8/30(VRF default): path 10.0.3.2 is the bestpath, add to the multipath list
2023/11/28 15:24:07.132686 BGP: [VMWTX-J3P3K] 203.0.113.8/30(VRF default): starting mpath update, newbest 10.0.3.2 num candidates 1 old-mpath-count 0 old-cum-bw 0
2023/11/28 15:24:07.132689 BGP: [YW4BC-Y5TG6] 203.0.113.8/30(VRF default): comparing candidate 10.0.3.2 with existing mpath NONE
2023/11/28 15:24:07.132691 BGP: [S7KWG-REZFR] 203.0.113.8/30(VRF default): New mpath count (incl newbest) 1 mpath-change NO all_paths_lb 0 cum_bw 0
2023/11/28 15:24:07.132692 BGP: [GVV1N-MH1P0] bgp_process_main_one: p=203.0.113.8/30(VRF default) afi=IPv4, safi=unicast, old_select=0x60d0000327e0, new_select=0x60d0000327e0
2023/11/28 15:24:07.204492 BGP: [M7Q4P-46WDR] vty[29]@> enable
2023/11/28 15:24:07.204903 BGP: [M7Q4P-46WDR] vty[29]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:24:08.339298 BGP: [M7Q4P-46WDR] vty[29]@> enable
2023/11/28 15:24:08.339867 BGP: [M7Q4P-46WDR] vty[29]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:24:09.473455 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:24:09.473885 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:24:10.453092 BGP: [T5AAP-5GA85] 10.0.4.2(Unknown) rcvd UPDATE w/ attr: nexthop 198.51.100.10, origin i, path 64503
2023/11/28 15:24:10.453097 BGP: [PCFFM-WMARW] 10.0.4.2(Unknown) rcvd UPDATE wlen 0 attrlen 20 alen 5
2023/11/28 15:24:10.453119 BGP: [YCKEM-GB33T] 10.0.4.2(Unknown) rcvd 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:10.453160 BGP: [T5AAP-5GA85] 10.0.4.2(Unknown) rcvd UPDATE w/ attr: nexthop 198.51.100.10, origin i, path 64503
2023/11/28 15:24:10.453161 BGP: [PCFFM-WMARW] 10.0.4.2(Unknown) rcvd UPDATE wlen 0 attrlen 20 alen 5
2023/11/28 15:24:10.453168 BGP: [YCKEM-GB33T] 10.0.4.2(Unknown) rcvd 203.0.113.8/30 IPv4 unicast
2023/11/28 15:24:10.503285 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.0/30
2023/11/28 15:24:10.503294 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032cc0
2023/11/28 15:24:10.503318 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.0/30
2023/11/28 15:24:10.503320 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032cc0
2023/11/28 15:24:10.503327 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.0/30
2023/11/28 15:24:10.503328 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032cc0
2023/11/28 15:24:10.503358 BGP: [V7N4G-NR80B] bgp_process_main_one: p=203.0.113.8/30(VRF default) afi=IPv4, safi=unicast start
2023/11/28 15:24:10.503360 BGP: [KQ9MH-8EWT4] 203.0.113.8/30(VRF default): path 10.0.4.2 is the initial bestpath
2023/11/28 15:24:10.503362 BGP: [HW35T-M56MT] 203.0.113.8/30(VRF default): Comparing path 10.0.3.2 flags 0x418 with path 10.0.4.2 flags 0x411
2023/11/28 15:24:10.503364 BGP: [PX4TR-N07FM] 203.0.113.8/30: path 10.0.3.2 and path 10.0.4.2 are equal via multipath-relax
2023/11/28 15:24:10.503365 BGP: [VYAZS-C34JV] 203.0.113.8/30: path 10.0.3.2 wins over path 10.0.4.2 due to Router-ID comparison
2023/11/28 15:24:10.503366 BGP: [N6CTF-2RSKS] 203.0.113.8/30(VRF default): After path selection, newbest is path 10.0.3.2 oldbest was 10.0.3.2
2023/11/28 15:24:10.503368 BGP: [HW35T-M56MT] 203.0.113.8/30(VRF default): Comparing path 10.0.4.2 flags 0x411 with path 10.0.3.2 flags 0x418
2023/11/28 15:24:10.503368 BGP: [PX4TR-N07FM] 203.0.113.8/30: path 10.0.4.2 and path 10.0.3.2 are equal via multipath-relax
2023/11/28 15:24:10.503369 BGP: [GBNS3-K6VE8] 203.0.113.8/30: path 10.0.4.2 loses to path 10.0.3.2 due to Router-ID comparison
2023/11/28 15:24:10.503370 BGP: [TJA2B-65T18] 203.0.113.8/30(VRF default): path 10.0.4.2 is equivalent to the bestpath, add to the multipath list
2023/11/28 15:24:10.503371 BGP: [ZF63D-VT50R] 203.0.113.8/30(VRF default): path 10.0.3.2 is the bestpath, add to the multipath list
2023/11/28 15:24:10.503373 BGP: [VMWTX-J3P3K] 203.0.113.8/30(VRF default): starting mpath update, newbest 10.0.3.2 num candidates 2 old-mpath-count 0 old-cum-bw 0
2023/11/28 15:24:10.503376 BGP: [YW4BC-Y5TG6] 203.0.113.8/30(VRF default): comparing candidate 10.0.3.2 with existing mpath NONE
2023/11/28 15:24:10.503377 BGP: [YW4BC-Y5TG6] 203.0.113.8/30(VRF default): comparing candidate 10.0.4.2 with existing mpath NONE
2023/11/28 15:24:10.503379 BGP: [QEKE4-Y39EP] 203.0.113.8/30: add mpath path 10.0.4.2 nexthop 198.51.100.10, cur count 2
2023/11/28 15:24:10.503381 BGP: [S7KWG-REZFR] 203.0.113.8/30(VRF default): New mpath count (incl newbest) 2 mpath-change YES all_paths_lb 0 cum_bw 0
2023/11/28 15:24:10.503383 BGP: [GVV1N-MH1P0] bgp_process_main_one: p=203.0.113.8/30(VRF default) afi=IPv4, safi=unicast, old_select=0x60d0000327e0, new_select=0x60d0000327e0
2023/11/28 15:24:10.503385 BGP: [RR6EG-07TGC] bgp_process_main_one: setting SELECTED flag
2023/11/28 15:24:10.503386 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.8/30
2023/11/28 15:24:10.503387 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.8/30, selected=0x60d0000327e0
2023/11/28 15:24:10.503393 BGP: [MAXTH-W5WMN] 10.0.5.2 suppress UPDATE w/ attr: nexthop 10.0.3.2, origin i, localpref 100, path 64502
2023/11/28 15:24:10.503394 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.8/30
2023/11/28 15:24:10.503395 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.8/30, selected=0x60d0000327e0
2023/11/28 15:24:10.503399 BGP: [MAXTH-W5WMN] 10.0.4.2 suppress UPDATE w/ attr: nexthop 0.0.0.0, origin i, path 64502
2023/11/28 15:24:10.503400 BGP: [Z38CW-7NYWG] group_announce_route_walkcb: afi=IPv4, safi=unicast, p=203.0.113.8/30
2023/11/28 15:24:10.503401 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.8/30, selected=0x60d0000327e0
2023/11/28 15:24:10.503404 BGP: [MAXTH-W5WMN] 10.0.1.2 suppress UPDATE w/ attr: nexthop 10.0.3.2, origin i, localpref 100, path 64502
2023/11/28 15:24:10.503488 BGP: [TN0HX-6G1RR] u1:s1 send UPDATE w/ attr: nexthop 198.51.100.10, origin i, localpref 100, path 64503
2023/11/28 15:24:10.503491 BGP: [HVRWP-5R9NQ] u1:s1 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:10.503497 BGP: [WEV7K-2GAQ5] u1:s1 send UPDATE len 62 (max message len: 65535) numpfx 1
2023/11/28 15:24:10.503501 BGP: [MBFVT-8GSC6] u1:s1 10.0.5.2 send UPDATE w/ nexthop 198.51.100.10
2023/11/28 15:24:10.503519 BGP: [TN0HX-6G1RR] u2:s2 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, path 64503
2023/11/28 15:24:10.503520 BGP: [HVRWP-5R9NQ] u2:s2 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:10.503523 BGP: [WEV7K-2GAQ5] u2:s2 send UPDATE len 53 (max message len: 4096) numpfx 1
2023/11/28 15:24:10.503525 BGP: [MBFVT-8GSC6] u2:s2 10.0.3.2 send UPDATE w/ nexthop 10.0.3.1
2023/11/28 15:24:10.503531 BGP: [TN0HX-6G1RR] u3:s3 send UPDATE w/ attr: nexthop 198.51.100.10, origin i, localpref 100, path 64503
2023/11/28 15:24:10.503532 BGP: [HVRWP-5R9NQ] u3:s3 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:10.503534 BGP: [WEV7K-2GAQ5] u3:s3 send UPDATE len 56 (max message len: 4096) numpfx 1
2023/11/28 15:24:10.503536 BGP: [MBFVT-8GSC6] u3:s3 10.0.1.2 send UPDATE w/ nexthop 198.51.100.10
2023/11/28 15:24:10.602582 BGP: [M7Q4P-46WDR] vty[30]@> enable
2023/11/28 15:24:10.602970 BGP: [M7Q4P-46WDR] vty[30]@# show ip bgp 203.0.113.8/30 json
2023/11/28 15:24:11.410246 BGP: [PCFFM-WMARW] 10.0.4.2(Unknown) rcvd UPDATE wlen 0 attrlen 0 alen 0
2023/11/28 15:24:11.410257 BGP: [G3V0W-FSWRC] bgp_best_path_select_defer: processing route for IPv4 Unicast : cnt 0
2023/11/28 15:24:11.410273 BGP: [M59KS-A3ZXZ] bgp_update_receive: rcvd End-of-RIB for IPv4 Unicast from 10.0.4.2 in vrf default
2023/11/28 15:24:11.659469 BGP: [K9G3A-CJ5QQ] u2:s4 announcing routes upon coalesce timer expiry(1250 ms)
2023/11/28 15:24:11.659482 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=198.51.100.0/24, selected=0x60d000032570
2023/11/28 15:24:11.659524 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.0/30, selected=0x60d000032cc0
2023/11/28 15:24:11.659530 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.4/30, selected=0x60d000032a50
2023/11/28 15:24:11.659535 BGP: [T5JFA-13199] subgroup_process_announce_selected: p=203.0.113.8/30, selected=0x60d0000327e0
2023/11/28 15:24:11.659602 BGP: [TN0HX-6G1RR] u2:s4 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, path 64502
2023/11/28 15:24:11.659605 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 198.51.100.0/24 IPv4 unicast
2023/11/28 15:24:11.659610 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 203.0.113.8/30 IPv4 unicast
2023/11/28 15:24:11.659614 BGP: [WEV7K-2GAQ5] u2:s4 send UPDATE len 57 (max message len: 4096) numpfx 2
2023/11/28 15:24:11.659625 BGP: [MBFVT-8GSC6] u2:s4 10.0.4.2 send UPDATE w/ nexthop 10.0.4.1
2023/11/28 15:24:11.659634 BGP: [TN0HX-6G1RR] u2:s4 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, path 64503
2023/11/28 15:24:11.659635 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 203.0.113.0/30 IPv4 unicast
2023/11/28 15:24:11.659638 BGP: [WEV7K-2GAQ5] u2:s4 send UPDATE len 53 (max message len: 4096) numpfx 1
2023/11/28 15:24:11.659641 BGP: [MBFVT-8GSC6] u2:s4 10.0.4.2 send UPDATE w/ nexthop 10.0.4.1
2023/11/28 15:24:11.659648 BGP: [TN0HX-6G1RR] u2:s4 send UPDATE w/ attr: nexthop 0.0.0.0, origin i, localpref 100, path (64511) 64499
2023/11/28 15:24:11.659649 BGP: [HVRWP-5R9NQ] u2:s4 send UPDATE 203.0.113.4/30 IPv4 unicast
2023/11/28 15:24:11.659652 BGP: [WEV7K-2GAQ5] u2:s4 send UPDATE len 53 (max message len: 4096) numpfx 1
2023/11/28 15:24:11.659653 BGP: [MBFVT-8GSC6] u2:s4 10.0.4.2 send UPDATE w/ nexthop 10.0.4.1

Versions

  • OS Version: Debian 10
  • Kernel: 6.1.0-0.deb11.5-amd64
@louis-6wind louis-6wind added the triage Needs further investigation label Nov 28, 2023
@louis-6wind louis-6wind mentioned this issue Nov 28, 2023
Merged
louis-6wind added a commit to louis-6wind/frr that referenced this issue Nov 28, 2023
@louis-6wind
topotests: tmp fix delay in bgp_peer_type_multipath_relax
70a1361 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
louis-6wind added a commit to louis-6wind/frr that referenced this issue Nov 28, 2023
@louis-6wind
topotests: tmp fix delay in bgp_peer_type_multipath_relax
48a1b5c 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
louis-6wind added a commit to louis-6wind/frr that referenced this issue Nov 29, 2023
@louis-6wind
topotests: tmp fix delay in bgp_peer_type_multipath_relax
61d57cc 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
louis-6wind added a commit to louis-6wind/frr that referenced this issue Nov 29, 2023
@louis-6wind
topotests: tmp fix delay in bgp_peer_type_multipath_relax
11a7b1b 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
louis-6wind added a commit to louis-6wind/frr that referenced this issue Dec 14, 2023
@louis-6wind
topotests: tmp fix delay in bgp_peer_type_multipath_relax
09a3919 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
louis-6wind added a commit to louis-6wind/frr that referenced this issue Dec 14, 2023
@louis-6wind
topotests: tmp fix delay in bgp_peer_type_multipath_relax
73ce39c 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
cscarpitta pushed a commit to cscarpitta/frr that referenced this issue Feb 9, 2024
@louis-6wind @cscarpitta
topotests: tmp fix delay in bgp_peer_type_multipath_relax
cd17e65 
Workaround an issue in bgp_peer_type_multipath_relax.

Link: FRRouting#14895
Signed-off-by: Louis Scalbert <louis.scalbert@6wind.com>
@github-actions GitHub Actions
Copy link

This issue is stale because it has been open 180 days with no activity. Comment or remove the autoclose label in order to avoid having this issue closed.

@frrbot
Copy link

frrbot bot commented May 27, 2024

This issue will be automatically closed in the specified period unless there is further activity.

@frrbot frrbot bot closed this as completed Jun 3, 2024
@frrbot frrbot bot removed the autoclose label Jun 3, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
triage Needs further investigation
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@louis-6wind