bird 1.5.0-4 ospf Inactivity timer expired.

[Ondrej Zajicek]

On Tue, Oct 27, 2015 at 10:25:06AM +0600, Andrey Kitsul wrote:
> Hello,
> 
> Packets debug in attachment (TRACE + WARN), problem: "2015-10-27 10:02:28
> <TRACE> ospf_area0: Inactivity timer expired for nbr 195.x.y.z2 on
> eth0.1889"
> In pcap dump I see ospf Hello packets per second from other ospf nbr.
> 
> 2015-10-27 10:02:25 <TRACE> ospf_area0: HELLO packet received from nbr 195.x.y.z4 on greegw4
> 2015-10-27 10:02:25 <TRACE> ospf_area0: HELLO packet received from nbr 195.x.y.z2 on greegw2
> 2015-10-27 10:02:25 <TRACE> ospf_area0: HELLO packet received from nbr 195.a.b.c1 on eth1.2508
> 2015-10-27 10:02:25 <TRACE> ospf_area0: HELLO packet received from nbr 195.x.y.z4 on eth0.1889

> 2015-10-27 10:02:26 <TRACE> kernel1: Scanning routing table
> 2015-10-27 10:02:26 <TRACE> kernel1: Pruning table master
> 2015-10-27 10:02:26 <TRACE> kernel1: Pruning inherited routes
> 2015-10-27 10:02:26 <WARN> Event 0x0000000000442440 0x0000000000000000 took 1619 ms
> 2015-10-27 10:02:26 <TRACE> ospf_area0: HELLO packet sent via greegw4
> 2015-10-27 10:02:26 <TRACE> ospf_area0: HELLO packet sent via greegw2
> 2015-10-27 10:02:26 <TRACE> ospf_area0: HELLO packet sent via eth1.2508
> 2015-10-27 10:02:26 <TRACE> ospf_area0: HELLO packet sent via eth0.1889
> 2015-10-27 10:02:28 <TRACE> ospf_area0: HELLO packet sent via eth0.1889
> 2015-10-27 10:02:28 <TRACE> ospf_area0: HELLO packet sent via eth1.2508
> 2015-10-27 10:02:28 <TRACE> ospf_area0: HELLO packet sent via greegw2
> 2015-10-27 10:02:28 <TRACE> ospf_area0: HELLO packet sent via greegw4
> 2015-10-27 10:02:28 <TRACE> ospf_area0: Inactivity timer expired for nbr 195.x.y.z2 on eth0.1889

Hi

The long kernel scan caused remaining events to be queued and this
sequence of timer events (containing neighbor expiration event) was
processed before BIRD started to process incoming socket events (e.g. the
incoming HELLO). The incoming hello was processed immediately after that:

> 2015-10-27 10:02:28 <TRACE> ospf_area0: HELLO packet received from nbr 195.x.y.z2 on eth0.1889
> 2015-10-27 10:02:28 <TRACE> ospf_area0: New neighbor 195.x.y.z2 on eth0.1889, IP address 195.x.y.z2

-- 
Elen sila lumenn' omentielvo

Ondrej 'Santiago' Zajicek (email: santiago at crfreenet.org)
OpenPGP encrypted e-mails preferred (KeyID 0x11DEADC3, wwwkeys.pgp.net)
"To err is human -- to blame it on a computer is even more so."
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 181 bytes
Desc: Digital signature
URL: <http://trubka.network.cz/pipermail/bird-users/attachments/20151029/3009231f/attachment.asc>