This is based on Quagga proposed 6 branch of Feb 10 (git f48d5b9957 -
which does no longer exist, [no] thanks to rebase on a public
git) on FreeBSD 10.2.
Zebra seems to fail delete any routes in FreeBSD RIB. It fails with
updates (better routes to different interface) and
it fails to remove them when quagga is killed.
If you want to try it yourself, then clone my BGPtool from
https://git-us.netdef.org/scm/netdef/bgptool.git
This would be run on a Linux node connected over 2 interfaces to the
FreeBSD DUT.
Test is “test_2ibgp_localpref” (see README_test_2ibgp_localpref.md
for description)
Test basically announces the same route with different Localpref to
Quagga. First a lower
localpref (which gets installed correctly), then a higher one (which is
ignored).
Killing BGP peer and killing Zebra/bgpd does not remove the route.
Most likely you can reproduce the same issue with any other BGP neighbor
as well
From the zebra log:
2016/02/17 17:50:03 ZEBRA: rib_delnode: 30.0.9.0/24 vrf 0: rn
0x8020e09c0, rib 0x8020e0a10, removing
2016/02/17 17:50:03 ZEBRA: kernel_rtm_ipv4: odd command RTM_DELETE for
flags 1
2016/02/17 17:50:03 ZEBRA: rib_process: 30.0.9.0/24 vrf 0: rn
0x8020e09c0, removing rib 0x8020e0a10
2016/02/17 17:50:03 ZEBRA: rib_unlink: 30.0.9.0/24 vrf 0: rn
0x8020e09c0, rib 0x8020e0a10
2016/02/17 17:50:03 ZEBRA: rib_gc_dest: 30.0.9.0/24 vrf 0: removing dest
from table
Notice the “kernel_rtm_ipv4: odd command RTM_DELETE for flags 1”.
kernel_rtm_ipv4 function checks for NEXTHOP_FLAG_FIB which is not set.
Full bgpd.log and zebra.log attached
- Martin
2016/02/17 17:48:46 ZEBRA: MESSAGE: ZEBRA_INTERFACE_UP eth2
2016/02/17 17:48:46 ZEBRA: MESSAGE: ZEBRA_INTERFACE_UP eth3
2016/02/17 17:48:46 ZEBRA: Zebra 0.99.25-dev-20160207094825-git.f48d5b9
starting: vty@2601
2016/02/17 17:48:46 ZEBRA: MESSAGE: ZEBRA_INTERFACE_UP eth2
2016/02/17 17:48:46 ZEBRA: MESSAGE: ZEBRA_INTERFACE_ADDRESS_ADD
192.168.1.101/24 on eth2
2016/02/17 17:48:46 ZEBRA: rib_add_ipv4: calling rib_addnode (0x8020e03d0,
0x8020e0420)
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.1.0/24 vrf 0: rn 0x8020e03d0, rib
0x8020e0420
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.1.0/24 vrf 0: adding dest to table
2016/02/17 17:48:46 ZEBRA: rib_add_ipv4: calling rib_addnode (0x8020e0510,
0x8020e0560)
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.1.0/24 (MRIB) vrf 0: rn
0x8020e0510, rib 0x8020e0560
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.1.0/24 (MRIB) vrf 0: adding dest
to table
2016/02/17 17:48:46 ZEBRA: MESSAGE: ZEBRA_INTERFACE_UP eth3
2016/02/17 17:48:46 ZEBRA: MESSAGE: ZEBRA_INTERFACE_ADDRESS_ADD
192.168.2.101/24 on eth3
2016/02/17 17:48:46 ZEBRA: rib_add_ipv4: calling rib_addnode (0x8020e06a0,
0x8020e06f0)
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.2.0/24 vrf 0: rn 0x8020e06a0, rib
0x8020e06f0
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.2.0/24 vrf 0: adding dest to table
2016/02/17 17:48:46 ZEBRA: rib_add_ipv4: calling rib_addnode (0x8020e07e0,
0x8020e0830)
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.2.0/24 (MRIB) vrf 0: rn
0x8020e07e0, rib 0x8020e0830
2016/02/17 17:48:46 ZEBRA: rib_link: 192.168.2.0/24 (MRIB) vrf 0: adding dest
to table
2016/02/17 17:48:48 ZEBRA: zebra message comes from socket [13]
2016/02/17 17:48:48 ZEBRA: zebra message received [ZEBRA_HELLO] 1 in VRF 0
2016/02/17 17:48:48 ZEBRA: client 13 says hello and bids fair to announce only
bgp routes
2016/02/17 17:48:48 ZEBRA: zebra message comes from socket [13]
2016/02/17 17:48:48 ZEBRA: zebra message received [ZEBRA_ROUTER_ID_ADD] 0 in
VRF 0
2016/02/17 17:48:48 ZEBRA: zebra message comes from socket [13]
2016/02/17 17:48:48 ZEBRA: zebra message received [ZEBRA_INTERFACE_ADD] 0 in
VRF 0
2016/02/17 17:49:13 ZEBRA: vty[??]@> enable
2016/02/17 17:49:29 ZEBRA: zebra message comes from socket [14]
2016/02/17 17:49:29 ZEBRA: zebra message received [ZEBRA_IPV4_NEXTHOP_LOOKUP] 4
in VRF 0
2016/02/17 17:49:29 ZEBRA: zread_ipv4_nexthop_lookup: looking up 192.168.1.1
2016/02/17 17:49:29 ZEBRA: zsend_ipv4_nexthop_lookup: Matching rib entry found.
2016/02/17 17:49:29 ZEBRA: zebra message comes from socket [13]
2016/02/17 17:49:29 ZEBRA: zebra message received [ZEBRA_IPV4_ROUTE_ADD] 20 in
VRF 0
2016/02/17 17:49:29 ZEBRA: rib_link: 30.0.9.0/24 vrf 0: rn 0x8020e09c0, rib
0x8020e08d0
2016/02/17 17:49:29 ZEBRA: rib_link: 30.0.9.0/24 vrf 0: adding dest to table
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: called rib_addnode
(0x8020e09c0, 0x8020e08d0) on new RIB entry
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: dumping RIB entry
0x8020e08d0 for 30.0.9.0/24 vrf 0
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: refcnt == 0, uptime ==
1455760169, type == 9, table == 0
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: metric == 0, distance ==
200, flags == 9, status == 0
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: nexthop_num == 1,
nexthop_active_num == 0, nexthop_fib_num == 0
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: NH 192.168.1.1 with flags
2016/02/17 17:49:29 ZEBRA: rib_add_ipv4_multipath: dump complete
2016/02/17 17:49:29 ZEBRA: kernel_rtm_ipv4: 30.0.9.0/24: successfully did NH
192.168.1.1
2016/02/17 17:49:29 ZEBRA: rtm_read: RTM_ADD 30.0.9.0/24: done Ok
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: rn 0x8020e09c0, rib
0x8020e08d0: NOT removed, selected
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: dumping RIB entry 0x8020e08d0
for 30.0.9.0/24 vrf 0
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: refcnt == 0, uptime ==
1455760169, type == 9, table == 0
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: metric == 0, distance == 200,
flags == 25, status == 4
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: nexthop_num == 1,
nexthop_active_num == 1, nexthop_fib_num == 0
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: NH 192.168.1.1 with flags
ACTIVE FIB
2016/02/17 17:49:29 ZEBRA: rib_lookup_and_dump: dump complete
2016/02/17 17:49:39 ZEBRA: zebra message comes from socket [14]
2016/02/17 17:49:39 ZEBRA: zebra message received [ZEBRA_IPV4_NEXTHOP_LOOKUP] 4
in VRF 0
2016/02/17 17:49:39 ZEBRA: zread_ipv4_nexthop_lookup: looking up 192.168.2.1
2016/02/17 17:49:39 ZEBRA: zsend_ipv4_nexthop_lookup: Matching rib entry found.
2016/02/17 17:49:39 ZEBRA: zebra message comes from socket [13]
2016/02/17 17:49:39 ZEBRA: zebra message received [ZEBRA_IPV4_ROUTE_ADD] 20 in
VRF 0
2016/02/17 17:49:39 ZEBRA: rib_link: 30.0.9.0/24 vrf 0: rn 0x8020e09c0, rib
0x8020e0a10
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: called rib_addnode
(0x8020e09c0, 0x8020e0a10) on new RIB entry
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: dumping RIB entry
0x8020e0a10 for 30.0.9.0/24 vrf 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: refcnt == 0, uptime ==
1455760179, type == 9, table == 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: metric == 0, distance ==
200, flags == 9, status == 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: nexthop_num == 1,
nexthop_active_num == 0, nexthop_fib_num == 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: NH 192.168.2.1 with flags
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: dump complete
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: calling rib_delnode
(0x8020e09c0, 0x8020e08d0) on existing RIB entry
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: dumping RIB entry
0x8020e08d0 for 30.0.9.0/24 vrf 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: refcnt == 0, uptime ==
1455760169, type == 9, table == 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: metric == 0, distance ==
200, flags == 25, status == 4
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: nexthop_num == 1,
nexthop_active_num == 1, nexthop_fib_num == 0
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: NH 192.168.1.1 with flags
ACTIVE FIB
2016/02/17 17:49:39 ZEBRA: rib_add_ipv4_multipath: dump complete
2016/02/17 17:49:39 ZEBRA: rib_delnode: 30.0.9.0/24 vrf 0: rn 0x8020e09c0, rib
0x8020e08d0, removing
2016/02/17 17:49:39 ZEBRA: kernel_rtm_ipv4: odd command RTM_CHANGE for flags 1
2016/02/17 17:49:39 ZEBRA: rib_process: 30.0.9.0/24 vrf 0: rn 0x8020e09c0,
removing rib 0x8020e08d0
2016/02/17 17:49:39 ZEBRA: rib_unlink: 30.0.9.0/24 vrf 0: rn 0x8020e09c0, rib
0x8020e08d0
2016/02/17 17:49:48 ZEBRA: zebra message comes from socket [14]
2016/02/17 17:49:48 ZEBRA: zebra message received [ZEBRA_IPV4_NEXTHOP_LOOKUP] 4
in VRF 0
2016/02/17 17:49:48 ZEBRA: zread_ipv4_nexthop_lookup: looking up 192.168.2.1
2016/02/17 17:49:48 ZEBRA: zsend_ipv4_nexthop_lookup: Matching rib entry found.
2016/02/17 17:49:48 ZEBRA: zebra message comes from socket [14]
2016/02/17 17:49:48 ZEBRA: zebra message received [ZEBRA_IPV4_NEXTHOP_LOOKUP] 4
in VRF 0
2016/02/17 17:49:48 ZEBRA: zread_ipv4_nexthop_lookup: looking up 192.168.1.1
2016/02/17 17:49:48 ZEBRA: zsend_ipv4_nexthop_lookup: Matching rib entry found.
2016/02/17 17:50:03 ZEBRA: zebra message comes from socket [13]
2016/02/17 17:50:03 ZEBRA: zebra message received [ZEBRA_IPV4_ROUTE_DELETE] 13
in VRF 0
2016/02/17 17:50:03 ZEBRA: rib_delnode: 30.0.9.0/24 vrf 0: rn 0x8020e09c0, rib
0x8020e0a10, removing
2016/02/17 17:50:03 ZEBRA: kernel_rtm_ipv4: odd command RTM_DELETE for flags 1
2016/02/17 17:50:03 ZEBRA: rib_process: 30.0.9.0/24 vrf 0: rn 0x8020e09c0,
removing rib 0x8020e0a10
2016/02/17 17:50:03 ZEBRA: rib_unlink: 30.0.9.0/24 vrf 0: rn 0x8020e09c0, rib
0x8020e0a10
2016/02/17 17:50:03 ZEBRA: rib_gc_dest: 30.0.9.0/24 vrf 0: removing dest from
table
2016/02/17 17:50:18 ZEBRA: connection closed socket [13]
2016/02/17 17:50:18 ZEBRA: client 13 disconnected. 0 bgp routes removed from
the rib
2016/02/17 17:50:18 ZEBRA: connection closed socket [14]
2016/02/17 17:50:18 ZEBRA: Terminating on signal
2016/02/17 17:48:48 BGP: BGPd 0.99.25-dev-20160207094825-git.f48d5b9 starting:
vty@2605, bgp@<all>:179 pid 1218
2016/02/17 17:48:48 BGP: Import timer expired.
2016/02/17 17:48:48 BGP: Zebra rcvd: router id update 192.168.2.101/32
2016/02/17 17:48:48 BGP: Zebra rcvd: interface add eth0
2016/02/17 17:48:48 BGP: Zebra rcvd: interface eth0 address add 10.211.55.37/24
2016/02/17 17:48:48 BGP: Zebra rcvd: interface add eth1
2016/02/17 17:48:48 BGP: Zebra rcvd: interface eth1 address add 1.1.1.2/24
2016/02/17 17:48:48 BGP: Zebra rcvd: interface add eth2
2016/02/17 17:48:48 BGP: Zebra rcvd: interface eth2 address add 192.168.1.101/24
2016/02/17 17:48:48 BGP: Zebra rcvd: interface add eth3
2016/02/17 17:48:48 BGP: Zebra rcvd: interface eth3 address add 192.168.2.101/24
2016/02/17 17:48:48 BGP: Zebra rcvd: interface add eth4
2016/02/17 17:48:48 BGP: Zebra rcvd: interface add lo
2016/02/17 17:48:48 BGP: Zebra rcvd: interface lo address add ::1/128
2016/02/17 17:48:48 BGP: Zebra rcvd: interface lo address add fe80::1/64
2016/02/17 17:48:48 BGP: Zebra rcvd: interface lo address add 127.0.0.1/8
2016/02/17 17:48:56 BGP: 192.168.1.1 [FSM] Timer (start timer expire).
2016/02/17 17:48:56 BGP: 192.168.1.1 [FSM] BGP_Start (Idle->Connect)
2016/02/17 17:48:56 BGP: 192.168.1.1 [Event] Connect start to 192.168.1.1 fd 14
2016/02/17 17:48:56 BGP: 192.168.1.1 [FSM] Non blocking connect waiting result
2016/02/17 17:48:56 BGP: 192.168.1.1 went from Idle to Connect
2016/02/17 17:48:56 BGP: 192.168.2.1 [FSM] Timer (start timer expire).
2016/02/17 17:48:56 BGP: 192.168.2.1 [FSM] BGP_Start (Idle->Connect)
2016/02/17 17:48:56 BGP: 192.168.2.1 [Event] Connect start to 192.168.2.1 fd 15
2016/02/17 17:48:56 BGP: 192.168.2.1 [FSM] Non blocking connect waiting result
2016/02/17 17:48:56 BGP: 192.168.2.1 went from Idle to Connect
2016/02/17 17:48:56 BGP: 192.168.1.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:48:56 BGP: 192.168.2.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:48:56 BGP: 192.168.1.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:48:56 BGP: 192.168.1.1 went from Connect to Active
2016/02/17 17:48:56 BGP: 192.168.2.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:48:56 BGP: 192.168.2.1 went from Connect to Active
2016/02/17 17:49:03 BGP: Import timer expired.
2016/02/17 17:49:06 BGP: 192.168.1.1 [FSM] Timer (connect timer expire)
2016/02/17 17:49:06 BGP: 192.168.1.1 [FSM] ConnectRetry_timer_expired
(Active->Connect)
2016/02/17 17:49:06 BGP: 192.168.1.1 [Event] Connect start to 192.168.1.1 fd 14
2016/02/17 17:49:06 BGP: 192.168.1.1 [FSM] Non blocking connect waiting result
2016/02/17 17:49:06 BGP: 192.168.1.1 went from Active to Connect
2016/02/17 17:49:06 BGP: 192.168.2.1 [FSM] Timer (connect timer expire)
2016/02/17 17:49:06 BGP: 192.168.2.1 [FSM] ConnectRetry_timer_expired
(Active->Connect)
2016/02/17 17:49:06 BGP: 192.168.2.1 [Event] Connect start to 192.168.2.1 fd 15
2016/02/17 17:49:06 BGP: 192.168.2.1 [FSM] Non blocking connect waiting result
2016/02/17 17:49:06 BGP: 192.168.2.1 went from Active to Connect
2016/02/17 17:49:06 BGP: 192.168.1.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:49:06 BGP: 192.168.2.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:49:06 BGP: 192.168.1.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:49:06 BGP: 192.168.1.1 went from Connect to Active
2016/02/17 17:49:06 BGP: 192.168.2.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:49:06 BGP: 192.168.2.1 went from Connect to Active
2016/02/17 17:49:13 BGP: vty[??]@> enable
2016/02/17 17:49:16 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:16 BGP: 192.168.1.1 [FSM] Timer (connect timer expire)
2016/02/17 17:49:16 BGP: 192.168.1.1 [FSM] ConnectRetry_timer_expired
(Active->Connect)
2016/02/17 17:49:16 BGP: 192.168.1.1 [Event] Connect start to 192.168.1.1 fd 15
2016/02/17 17:49:16 BGP: 192.168.1.1 [FSM] Non blocking connect waiting result
2016/02/17 17:49:16 BGP: 192.168.1.1 went from Active to Connect
2016/02/17 17:49:16 BGP: 192.168.2.1 [FSM] Timer (connect timer expire)
2016/02/17 17:49:16 BGP: 192.168.2.1 [FSM] ConnectRetry_timer_expired
(Active->Connect)
2016/02/17 17:49:16 BGP: 192.168.2.1 [Event] Connect start to 192.168.2.1 fd 16
2016/02/17 17:49:16 BGP: 192.168.2.1 [FSM] Non blocking connect waiting result
2016/02/17 17:49:16 BGP: 192.168.2.1 went from Active to Connect
2016/02/17 17:49:16 BGP: 192.168.1.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:49:16 BGP: 192.168.2.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:49:16 BGP: 192.168.1.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:49:16 BGP: 192.168.1.1 went from Connect to Active
2016/02/17 17:49:16 BGP: 192.168.2.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:49:16 BGP: 192.168.2.1 went from Connect to Active
2016/02/17 17:49:18 BGP: Import timer expired.
2016/02/17 17:49:18 BGP: [Event] BGP connection from host 192.168.1.1
2016/02/17 17:49:18 BGP: [Event] Make dummy peer structure until read Open
packet
2016/02/17 17:49:18 BGP: 192.168.1.1 [FSM] TCP_connection_open
(Active->OpenSent)
2016/02/17 17:49:18 BGP: 192.168.1.1 passive open
2016/02/17 17:49:18 BGP: 192.168.1.1 went from Active to OpenSent
2016/02/17 17:49:18 BGP: 192.168.1.1 rcv message type 1, length (excl. header)
10
2016/02/17 17:49:18 BGP: 192.168.1.1 rcv OPEN, version 4, remote-as (in open)
500, holdtime 90, id 192.168.1.1
2016/02/17 17:49:18 BGP: 192.168.1.1 [Event] Transfer accept BGP peer to real
(state Active)
2016/02/17 17:49:18 BGP: 192.168.1.1 sending OPEN, version 4, my as 500,
holdtime 60, id 192.168.2.101
2016/02/17 17:49:18 BGP: 192.168.1.1 send message type 1, length (incl. header)
59
2016/02/17 17:49:18 BGP: 192.168.1.1 rcvd OPEN w/ OPTION parameter len: 0
2016/02/17 17:49:18 BGP: 192.168.1.1 [Event] Accepting BGP peer delete
2016/02/17 17:49:18 BGP: 192.168.1.1 went from OpenSent to Deleted
2016/02/17 17:49:18 BGP: 192.168.1.1 [FSM] Receive_OPEN_message
(OpenSent->OpenConfirm)
2016/02/17 17:49:18 BGP: 192.168.1.1 sending KEEPALIVE
2016/02/17 17:49:18 BGP: 192.168.1.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:18 BGP: 192.168.1.1 went from OpenSent to OpenConfirm
2016/02/17 17:49:18 BGP: 192.168.1.1 rcv message type 4, length (excl. header) 0
2016/02/17 17:49:18 BGP: 192.168.1.1 KEEPALIVE rcvd
2016/02/17 17:49:18 BGP: 192.168.1.1 [FSM] Receive_KEEPALIVE_message
(OpenConfirm->Established)
2016/02/17 17:49:18 BGP: 192.168.1.1 went from OpenConfirm to Established
2016/02/17 17:49:18 BGP: %ADJCHANGE: neighbor 192.168.1.1 Up
2016/02/17 17:49:18 BGP: 192.168.1.1 sending KEEPALIVE
2016/02/17 17:49:18 BGP: 192.168.1.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:19 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:19 BGP: [Event] BGP connection from host 192.168.2.1
2016/02/17 17:49:19 BGP: [Event] Make dummy peer structure until read Open
packet
2016/02/17 17:49:19 BGP: 192.168.2.1 [FSM] TCP_connection_open
(Active->OpenSent)
2016/02/17 17:49:19 BGP: 192.168.2.1 passive open
2016/02/17 17:49:19 BGP: 192.168.2.1 went from Active to OpenSent
2016/02/17 17:49:19 BGP: 192.168.2.1 rcv message type 1, length (excl. header)
10
2016/02/17 17:49:19 BGP: 192.168.2.1 rcv OPEN, version 4, remote-as (in open)
500, holdtime 90, id 192.168.2.1
2016/02/17 17:49:19 BGP: 192.168.2.1 [Event] Transfer accept BGP peer to real
(state Active)
2016/02/17 17:49:19 BGP: 192.168.2.1 sending OPEN, version 4, my as 500,
holdtime 60, id 192.168.2.101
2016/02/17 17:49:19 BGP: 192.168.2.1 send message type 1, length (incl. header)
59
2016/02/17 17:49:19 BGP: 192.168.2.1 rcvd OPEN w/ OPTION parameter len: 0
2016/02/17 17:49:19 BGP: 192.168.2.1 [Event] Accepting BGP peer delete
2016/02/17 17:49:19 BGP: 192.168.2.1 went from OpenSent to Deleted
2016/02/17 17:49:19 BGP: 192.168.2.1 [FSM] Receive_OPEN_message
(OpenSent->OpenConfirm)
2016/02/17 17:49:19 BGP: 192.168.2.1 sending KEEPALIVE
2016/02/17 17:49:19 BGP: 192.168.2.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:19 BGP: 192.168.2.1 went from OpenSent to OpenConfirm
2016/02/17 17:49:19 BGP: 192.168.2.1 rcv message type 4, length (excl. header) 0
2016/02/17 17:49:19 BGP: 192.168.2.1 KEEPALIVE rcvd
2016/02/17 17:49:19 BGP: 192.168.2.1 [FSM] Receive_KEEPALIVE_message
(OpenConfirm->Established)
2016/02/17 17:49:19 BGP: 192.168.2.1 went from OpenConfirm to Established
2016/02/17 17:49:19 BGP: %ADJCHANGE: neighbor 192.168.2.1 Up
2016/02/17 17:49:19 BGP: 192.168.2.1 sending KEEPALIVE
2016/02/17 17:49:19 BGP: 192.168.2.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:20 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:21 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:22 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:22 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:23 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:24 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:24 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:25 BGP: 192.168.1.1 rcv message type 4, length (excl. header) 0
2016/02/17 17:49:25 BGP: 192.168.1.1 KEEPALIVE rcvd
2016/02/17 17:49:25 BGP: 192.168.1.1 rcv message type 4, length (excl. header) 0
2016/02/17 17:49:25 BGP: 192.168.1.1 KEEPALIVE rcvd
2016/02/17 17:49:25 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:25 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:26 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:27 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:28 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:29 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:29 BGP: 192.168.1.1 rcvd UPDATE w/ attr: nexthop 192.168.1.1,
origin i, localpref 150, path
2016/02/17 17:49:29 BGP: 192.168.1.1 rcvd 30.0.9.0/24
2016/02/17 17:49:29 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:29 BGP: Zebra send: IPv4 route add 30.0.9.0/24 nexthop
192.168.1.1 metric 0 count 1
2016/02/17 17:49:30 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:30 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:31 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:32 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:33 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:33 BGP: Import timer expired.
2016/02/17 17:49:33 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:34 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:34 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:35 BGP: 192.168.1.1 rcv message type 4, length (excl. header) 0
2016/02/17 17:49:35 BGP: 192.168.1.1 KEEPALIVE rcvd
2016/02/17 17:49:35 BGP: 192.168.1.1 rcv message type 4, length (excl. header) 0
2016/02/17 17:49:35 BGP: 192.168.1.1 KEEPALIVE rcvd
2016/02/17 17:49:35 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:35 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:36 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:37 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:38 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:38 BGP: 192.168.1.1 [FSM] Timer (keepalive timer expire)
2016/02/17 17:49:38 BGP: 192.168.1.1 sending KEEPALIVE
2016/02/17 17:49:38 BGP: 192.168.1.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:38 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:39 BGP: 192.168.2.1 rcvd UPDATE w/ attr: nexthop 192.168.2.1,
origin i, localpref 160, path
2016/02/17 17:49:39 BGP: 192.168.2.1 rcvd 30.0.9.0/24
2016/02/17 17:49:39 BGP: 192.168.2.1 [FSM] Timer (keepalive timer expire)
2016/02/17 17:49:39 BGP: 192.168.2.1 sending KEEPALIVE
2016/02/17 17:49:39 BGP: 192.168.2.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:39 BGP: Zebra send: IPv4 route add 30.0.9.0/24 nexthop
192.168.2.1 metric 0 count 1
2016/02/17 17:49:39 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:39 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:40 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:40 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:41 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:42 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:43 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:49:44 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:45 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:48 BGP: Performing BGP general scanning
2016/02/17 17:49:48 BGP: scanning IPv4 Unicast routing tables
2016/02/17 17:49:48 BGP: scanning IPv6 Unicast routing tables
2016/02/17 17:49:48 BGP: Import timer expired.
2016/02/17 17:49:49 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:50 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:54 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:55 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:49:58 BGP: 192.168.1.1 [FSM] Timer (keepalive timer expire)
2016/02/17 17:49:58 BGP: 192.168.1.1 sending KEEPALIVE
2016/02/17 17:49:58 BGP: 192.168.1.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:59 BGP: 192.168.2.1 [FSM] Timer (keepalive timer expire)
2016/02/17 17:49:59 BGP: 192.168.2.1 sending KEEPALIVE
2016/02/17 17:49:59 BGP: 192.168.2.1 send message type 4, length (incl. header)
19
2016/02/17 17:49:59 BGP: 192.168.1.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:50:00 BGP: 192.168.2.1 [FSM] Timer (routeadv timer expire)
2016/02/17 17:50:02 BGP: 192.168.1.1 [Event] BGP connection closed fd 15
2016/02/17 17:50:02 BGP: 192.168.2.1 [Event] BGP connection closed fd 16
2016/02/17 17:50:02 BGP: 192.168.1.1 [FSM] TCP_connection_closed
(Established->Clearing)
2016/02/17 17:50:02 BGP: %ADJCHANGE: neighbor 192.168.1.1 Down Peer closed the
session
2016/02/17 17:50:02 BGP: 192.168.1.1 went from Established to Clearing
2016/02/17 17:50:02 BGP: 192.168.2.1 [FSM] TCP_connection_closed
(Established->Clearing)
2016/02/17 17:50:02 BGP: %ADJCHANGE: neighbor 192.168.2.1 Down Peer closed the
session
2016/02/17 17:50:02 BGP: 192.168.2.1 went from Established to Clearing
2016/02/17 17:50:03 BGP: 192.168.1.1 [FSM] Clearing_Completed (Clearing->Idle)
2016/02/17 17:50:03 BGP: 192.168.1.1 went from Clearing to Idle
2016/02/17 17:50:03 BGP: 192.168.2.1 [FSM] Clearing_Completed (Clearing->Idle)
2016/02/17 17:50:03 BGP: 192.168.2.1 went from Clearing to Idle
2016/02/17 17:50:03 BGP: Zebra send: IPv4 route delete 30.0.9.0/24 metric 0
2016/02/17 17:50:03 BGP: Import timer expired.
2016/02/17 17:50:05 BGP: vty[??]@# sh ip bgp summ
2016/02/17 17:50:08 BGP: 192.168.1.1 [FSM] Timer (start timer expire).
2016/02/17 17:50:08 BGP: 192.168.1.1 [FSM] BGP_Start (Idle->Connect)
2016/02/17 17:50:08 BGP: 192.168.1.1 [Event] Connect start to 192.168.1.1 fd 15
2016/02/17 17:50:08 BGP: 192.168.1.1 [FSM] Non blocking connect waiting result
2016/02/17 17:50:08 BGP: 192.168.1.1 went from Idle to Connect
2016/02/17 17:50:08 BGP: 192.168.2.1 [FSM] Timer (start timer expire).
2016/02/17 17:50:08 BGP: 192.168.2.1 [FSM] BGP_Start (Idle->Connect)
2016/02/17 17:50:08 BGP: 192.168.2.1 [Event] Connect start to 192.168.2.1 fd 16
2016/02/17 17:50:08 BGP: 192.168.2.1 [FSM] Non blocking connect waiting result
2016/02/17 17:50:08 BGP: 192.168.2.1 went from Idle to Connect
2016/02/17 17:50:08 BGP: 192.168.1.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:50:08 BGP: 192.168.2.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:50:08 BGP: 192.168.1.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:50:08 BGP: 192.168.1.1 went from Connect to Active
2016/02/17 17:50:08 BGP: 192.168.2.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:50:08 BGP: 192.168.2.1 went from Connect to Active
2016/02/17 17:50:18 BGP: 192.168.1.1 [FSM] Timer (connect timer expire)
2016/02/17 17:50:18 BGP: 192.168.1.1 [FSM] ConnectRetry_timer_expired
(Active->Connect)
2016/02/17 17:50:18 BGP: 192.168.1.1 [Event] Connect start to 192.168.1.1 fd 15
2016/02/17 17:50:18 BGP: 192.168.1.1 [FSM] Non blocking connect waiting result
2016/02/17 17:50:18 BGP: 192.168.1.1 went from Active to Connect
2016/02/17 17:50:18 BGP: 192.168.2.1 [FSM] Timer (connect timer expire)
2016/02/17 17:50:18 BGP: 192.168.2.1 [FSM] ConnectRetry_timer_expired
(Active->Connect)
2016/02/17 17:50:18 BGP: 192.168.2.1 [Event] Connect start to 192.168.2.1 fd 16
2016/02/17 17:50:18 BGP: 192.168.2.1 [FSM] Non blocking connect waiting result
2016/02/17 17:50:18 BGP: 192.168.2.1 went from Active to Connect
2016/02/17 17:50:18 BGP: 192.168.1.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:50:18 BGP: 192.168.2.1 [Event] Connect failed (Operation now in
progress)
2016/02/17 17:50:18 BGP: 192.168.1.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:50:18 BGP: 192.168.1.1 went from Connect to Active
2016/02/17 17:50:18 BGP: 192.168.2.1 [FSM] TCP_connection_open_failed
(Connect->Active)
2016/02/17 17:50:18 BGP: 192.168.2.1 went from Connect to Active
2016/02/17 17:50:18 BGP: Import timer expired.
2016/02/17 17:50:18 BGP: Terminating on signal
2016/02/17 17:50:18 BGP: 192.168.1.1 went from Active to Deleted
2016/02/17 17:50:18 BGP: 192.168.2.1 went from Active to Deleted
2016/02/17 17:50:18 BGP: Static announcement went from Idle to Deleted
2016/02/17 17:50:18 BGP: VRF 0 is to be deleted.
2016/02/17 17:50:18 BGP: VRF 0 is to be disabled.
_______________________________________________
Quagga-dev mailing list
[email protected]
https://lists.quagga.net/mailman/listinfo/quagga-dev
