tinc - Dec 2005 - strange tinc error with many nodes

Hello,
 
we currently set up a large tinc network with 2 central Nodes (these nodes
connecting to each other).
 
All satellites (ca 40) connect to these both machines.  All containing two
ConntectTo fields (for backup)
 
e.g. (satellite)
 
Name = nfp_hy
Device = /dev/tun
PrivateKeyFile = /etc/tinc/nfp_hy/rsa_key.priv
ConnectTo = nfp_f_vpn
ConnectTo = nfp_c_vpn

If the count of satellites reaches 40, many strange error log entries are
visible (but only in the log file of the 2nd central
box). if we lower the number of satellites that connecting to these two boxes
the errors gone.
 
These error occurs an some satellites (less 3)

Dec 13 19:17:20 gt-fw-nfp-konstanz-scala tinc.nfp_kn_scala[2044]: Sending meta
data to nfp_c_vpn (1.2.3.4 port 30665) failed:
Resource temporarily unavailable
Dec 13 19:17:20 gt-fw-nfp-konstanz-scala last message repeated 7 times

And these are a short cut from the central box

Dec 13 14:36:27 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36758)
Dec 13 14:36:37 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_mz (8.9.0.1 port 43100)
Dec 13 14:36:38 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59716)
Dec 13 14:37:00 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52804)
Dec 13 14:37:14 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37358)
Dec 13 14:37:15 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36759)
Dec 13 14:37:28 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_mz (8.9.0.1 port 43103)
Dec 13 14:37:36 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_mz (8.9.0.1 port 43104)
Dec 13 14:37:36 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59717)
Dec 13 14:38:02 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_mz (8.9.0.1 port 43105)
Dec 13 14:38:08 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37359)
Dec 13 14:38:08 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52805)
Dec 13 14:38:08 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59718)
Dec 13 14:38:09 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36760)
Dec 13 14:39:06 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52813)
Dec 13 14:39:07 c tinc.nfp_c_vpn[17944]: Error while processing ADD_SUBNET from
nfp_c_rt (7.8.9.0 port 36761)
Dec 13 14:39:49 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59722)
Dec 13 14:39:54 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52829)
Dec 13 14:40:09 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36764)
Dec 13 14:40:37 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59723)
Dec 13 14:40:46 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52833)
Dec 13 14:41:42 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59724)
Dec 13 14:41:42 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36765)
Dec 13 14:41:42 c tinc.nfp_c_vpn[17944]: Error while processing ADD_SUBNET from
nfp_hl_event (9.0.1.2 port 52836)
Dec 13 14:42:39 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59725)
Dec 13 14:42:44 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37365)
Dec 13 14:42:44 c tinc.nfp_c_vpn[17944]: Error while processing ADD_SUBNET from
nfp_hl_event (9.0.1.2 port 52866)
Dec 13 14:43:53 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52883)
Dec 13 14:43:56 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59726)
Dec 13 14:45:21 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52889)
Dec 13 14:45:21 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37366)
Dec 13 14:45:21 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59729)
Dec 13 14:46:37 c tinc.nfp_c_vpn[17944]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 52907)
Dec 13 14:49:08 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37372)
Dec 13 14:49:25 c tinc.nfp_c_vpn[18195]: Error while processing ADD_SUBNET from
nfp_c_rt (7.8.9.0 port 36773)
Dec 13 14:49:26 c tinc.nfp_c_vpn[18195]: Error while processing METAKEY from
nfp_hl_event (9.0.1.2 port 52954)
Dec 13 14:50:57 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37373)
Dec 13 14:50:57 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59736)
Dec 13 14:50:57 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 53068)
Dec 13 14:50:57 c tinc.nfp_c_vpn[18195]: Error while processing METAKEY from
nfp_c_rt (7.8.9.0 port 36776)
Dec 13 14:52:33 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37376)
Dec 13 14:52:34 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59737)
Dec 13 14:52:34 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 53151)
Dec 13 14:52:34 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36777)
Dec 13 14:54:11 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59740)
Dec 13 14:54:17 c tinc.nfp_c_vpn[18195]: Error while processing ADD_SUBNET from
nfp_hl_event (9.0.1.2 port 53163)
Dec 13 14:55:48 c tinc.nfp_c_vpn[18195]: Error while processing ADD_SUBNET from
nfp_c_rt (7.8.9.0 port 36778)
Dec 13 14:55:48 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37377)
Dec 13 14:55:48 c tinc.nfp_c_vpn[18195]: Error while processing ADD_SUBNET from
nfp_c_luxur (3.4.5.6 port 59741)
Dec 13 14:57:35 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59742)
Dec 13 14:57:59 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37380)
Dec 13 14:57:59 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 53243)
Dec 13 14:58:00 c tinc.nfp_c_vpn[18195]: Error while processing ADD_SUBNET from
nfp_c_rt (7.8.9.0 port 36781)
Dec 13 14:59:24 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_luxur (3.4.5.6 port 59745)
Dec 13 14:59:55 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36782)
Dec 13 14:59:55 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37381)
Dec 13 14:59:58 c tinc.nfp_c_vpn[18195]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 53372)
Dec 13 15:01:18 c tinc.nfp_c_vpn[18440]: Error while processing ADD_SUBNET from
nfp_h (213.160.23.122 port 1600)
Dec 13 15:01:49 c tinc.nfp_c_vpn[18440]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37384)
Dec 13 15:01:59 c tinc.nfp_c_vpn[18440]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36785)
Dec 13 15:01:59 c tinc.nfp_c_vpn[18440]: Error while processing ADD_EDGE from
nfp_hl_event (9.0.1.2 port 53421)
Dec 13 15:02:31 c tinc.nfp_c_vpn[18440]: Error while processing ADD_SUBNET from
nfp_h (213.160.23.122 port 1605)
Dec 13 15:03:46 c tinc.nfp_c_vpn[18440]: Error while processing ADD_EDGE from
nfp_uh (6.7.8.9 port 37385)
Dec 13 15:04:01 c tinc.nfp_c_vpn[18440]: Error while processing ADD_EDGE from
nfp_c_rt (7.8.9.0 port 36786)

The connection most times work. Tinc is 1.0.4 or 1.0.3 no difference between.

There are reasons for these interruptions? Do we something wrong?
 
Thanks in advance
 
Kai

On Tue, Dec 13, 2005 at 06:40:12PM +0100, Kai K?hler wrote:
> we currently set up a large tinc network with 2 central Nodes (these
> nodes connecting to each other).
[...]
> If the count of satellites reaches 40, many strange error log entries
> are visible (but only in the log file of the 2nd central box). if we
> lower the number of satellites that connecting to these two boxes the
> errors gone.
>  
> These error occurs an some satellites (less 3)
> 
> Dec 13 19:17:20 gt-fw-nfp-konstanz-scala tinc.nfp_kn_scala[2044]:
> Sending meta data to nfp_c_vpn (1.2.3.4 port 30665) failed: Resource
> temporarily unavailable Dec 13 19:17:20 gt-fw-nfp-konstanz-scala last
> message repeated 7 times
[...]
> The connection most times work. Tinc is 1.0.4 or 1.0.3 no difference
> between.
> 
> There are reasons for these interruptions? Do we something wrong?
Probably because of small TCP buffers or slow network connections. Try
adding "BlockingTCP = yes" to all host config files.

-- 
Met vriendelijke groet / with kind regards,
    Guus Sliepen <guus@sliepen.eu.org>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 189 bytes
Desc: Digital signature
Url :
http://brouwer.uvt.nl/pipermail/tinc/attachments/20051214/1ede42dc/attachment.pgp