Problems with packages being dropped between nodes in the vpn

Sun Dec 10 16:17:29 CET 2017

Hi
I have some problems with my vpn. Im running version 1.1pre15 on all nodes.

I have four nodes in my network.

Node1 -> connects to Node2
Node2 -> connects to Node1
Node3 -> connects to Node1 and Node2
Node4 -> connects to Node1 and Node2

The problem is the connection between Node3 and Node4. The traffic is going via Node1 and Node2. Its unstable. package drops almost all the time
It seems like the connection is more stable when it can connects directly, but its also packages dropping here too.

The date and time is the same on each node.

Do you have any ideas how to fix this?
Thank you

Best Regards,

Håvard Rabbe

Printout from syslog from each node

Node1
Dec 10 15:47:22 NL-VPN4 tinc.vpn[2763]: Packet is 2276 seqs in the future, dropped (1) from Node2 (10.20.15.2 port 655)

Node2
Dec 10 14:47:22 JOTVPN tinc.vpn[1021]: /dev/net/tun is a Linux tun/tap device (tap mode)
Dec 10 14:47:22 JOTVPN systemd-udevd[1024]: Could not generate persistent MAC address for vpn: No such file or directory
Dec 10 14:47:22 JOTVPN kernel: [256164.329649] device vpn entered promiscuous mode
Dec 10 14:47:22 JOTVPN kernel: [256164.329666] bridge: port 2(vpn) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.329670] bridge: port 2(vpn) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.344426] device vpn.2 entered promiscuous mode
Dec 10 14:47:22 JOTVPN kernel: [256164.344444] vlan2: port 2(vpn.2) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.344448] vlan2: port 2(vpn.2) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.350137] device vpn.903 entered promiscuous mode
Dec 10 14:47:22 JOTVPN kernel: [256164.350163] vlan903: port 2(vpn.903) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.350167] vlan903: port 2(vpn.903) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.356847] device vpn.100 entered promiscuous mode
Dec 10 14:47:22 JOTVPN kernel: [256164.356863] vlan100: port 2(vpn.100) entered forwarding state
Dec 10 14:47:22 JOTVPN kernel: [256164.356867] vlan100: port 2(vpn.100) entered forwarding state
Dec 10 14:47:22 JOTVPN tinc.vpn[1021]: Ready
Dec 10 14:47:37 JOTVPN kernel: [256179.357265] bridge: port 2(vpn) entered forwarding state
Dec 10 14:47:37 JOTVPN kernel: [256179.357294] vlan2: port 2(vpn.2) entered forwarding state
Dec 10 14:47:37 JOTVPN kernel: [256179.357306] vlan903: port 2(vpn.903) entered forwarding state
Dec 10 14:47:37 JOTVPN kernel: [256179.357316] vlan100: port 2(vpn.100) entered forwarding state
Dec 10 14:48:04 JOTVPN tinc.vpn[1021]: Packet is 934 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 14:48:04 JOTVPN tinc.vpn[1021]: Invalid packet seqno: 1977 != 0 from Node4 (10.20.37.2 port 655)
Dec 10 14:48:04 JOTVPN tinc.vpn[1021]: Handshake phase not finished yet from Node4 (10.20.37.2 port 655)
Dec 10 14:48:06 JOTVPN tinc.vpn[1021]: Packet is 820 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 14:48:08 JOTVPN tinc.vpn[1021]: Packet is 822 seqs in the future, dropped (2) from Node4 (10.20.37.2 port 655)
Dec 10 14:48:10 JOTVPN tinc.vpn[1021]: Packet is 824 seqs in the future, dropped (3) from Node4 (10.20.37.2 port 655)
Dec 10 14:48:12 JOTVPN tinc.vpn[1021]: Packet is 826 seqs in the future, dropped (4) from Node4 (10.20.37.2 port 655)
Dec 10 14:48:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 14:48:14 JOTVPN tinc.vpn[1021]: Lost 827 packets from Node4 (10.20.37.2 port 655)
Dec 10 14:58:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 15:08:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 15:17:01 JOTVPN CRON[1298]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Dec 10 15:18:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 15:21:51 JOTVPN tinc.vpn[1021]: Packet is 587 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:21:51 JOTVPN tinc.vpn[1021]: Invalid packet seqno: 598 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:21:52 JOTVPN tinc.vpn[1021]: Packet is 994 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:21:53 JOTVPN tinc.vpn[1021]: Packet is 838 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:21:56 JOTVPN tinc.vpn[1021]: Packet is 840 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:21:57 JOTVPN tinc.vpn[1021]: Packet is 842 seqs in the future, dropped (3) from Node3 (10.20.25.2 port 655)
Dec 10 15:21:57 JOTVPN tinc.vpn[1021]: Packet is 844 seqs in the future, dropped (4) from Node3 (10.20.25.2 port 655)
Dec 10 15:22:00 JOTVPN tinc.vpn[1021]: Lost 846 packets from Node3 (10.20.25.2 port 655)
Dec 10 15:23:13 JOTVPN systemd[1]: Started Session 89 of user jotadm.
Dec 10 15:23:13 JOTVPN console-kit-daemon[1658]: (process:1317): GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed
Dec 10 15:23:13 JOTVPN console-kit-daemon[1658]: missing action
Dec 10 15:28:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 15:38:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 15:43:44 JOTVPN systemd[1]: Started Session 90 of user jotadm.
Dec 10 15:43:44 JOTVPN console-kit-daemon[1658]: (process:1432): GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed
Dec 10 15:43:44 JOTVPN console-kit-daemon[1658]: missing action
Dec 10 15:44:16 JOTVPN console-kit-daemon[1658]: (process:1515): GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed
Dec 10 15:44:16 JOTVPN console-kit-daemon[1658]: missing action
Dec 10 15:44:16 JOTVPN console-kit-daemon[1658]: console-kit-daemon[1658]: GLib-CRITICAL: Source ID 190 was not found when attempting to remove it
Dec 10 15:44:16 JOTVPN console-kit-daemon[1658]: GLib-CRITICAL: Source ID 190 was not found when attempting to remove it
Dec 10 15:44:16 JOTVPN console-kit-daemon[1658]: console-kit-daemon[1658]: GLib-CRITICAL: Source ID 190 was not found when attempting to remove it
Dec 10 15:44:16 JOTVPN console-kit-daemon[1658]: GLib-CRITICAL: Source ID 190 was not found when attempting to remove it
Dec 10 15:47:22 JOTVPN tinc.vpn[1021]: Got REQ_KEY from Node4 while we already started a SPTPS session!
Dec 10 15:47:22 JOTVPN tinc.vpn[1021]: Got REQ_KEY from Node1 while we already started a SPTPS session!
Dec 10 15:47:22 JOTVPN tinc.vpn[1021]: Got REQ_KEY from Node3 while we already started a SPTPS session!
Dec 10 15:48:13 JOTVPN mpt-statusd: detected non-optimal RAID status
Dec 10 15:55:15 JOTVPN systemd[1]: Starting Cleanup of Temporary Directories...
Dec 10 15:55:15 JOTVPN systemd-tmpfiles[1528]: [/usr/lib/tmpfiles.d/var.conf:14] Duplicate line for path "/var/log", ignoring.
Dec 10 15:55:15 JOTVPN systemd[1]: Started Cleanup of Temporary Directories.
Dec 10 15:58:13 JOTVPN mpt-statusd: detected non-optimal RAID status

NODE3
Dec 10 15:47:22 Butikken tinc.vpn[592]: Packet is 4170 seqs in the future, dropped (1) from Node2 (10.20.15.2 port 655)
Dec 10 15:47:53 Butikken tinc.vpn[592]: Got REQ_KEY from Node4 while we already started a SPTPS session!
Dec 10 15:47:56 Butikken tinc.vpn[592]: Packet is 839 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:47:56 Butikken tinc.vpn[592]: Packet is 840 seqs in the future, dropped (2) from Node4 (10.20.37.2 port 655)
Dec 10 15:47:57 Butikken tinc.vpn[592]: Packet is 841 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:47:58 Butikken tinc.vpn[592]: Packet is 843 seqs in the future, dropped (2) from Node4 (10.20.37.2 port 655)
Dec 10 15:47:58 Butikken tinc.vpn[592]: Packet is 845 seqs in the future, dropped (3) from Node4 (10.20.37.2 port 655)
Dec 10 15:47:59 Butikken tinc.vpn[592]: Packet is 846 seqs in the future, dropped (4) from Node4 (10.20.37.2 port 655)
Dec 10 15:47:59 Butikken tinc.vpn[592]: Lost 848 packets from Node4 (10.20.37.2 port 655)
Dec 10 15:48:30 Butikken tinc.vpn[592]: Got REQ_KEY from Node4 while we already started a SPTPS session!
Dec 10 15:49:01 Butikken tinc.vpn[592]: Packet is 763 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:49:01 Butikken tinc.vpn[592]: Invalid packet seqno: 779 != 0 from Node4 (10.20.37.2 port 655)
Dec 10 15:49:02 Butikken tinc.vpn[592]: Packet is 839 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:49:03 Butikken tinc.vpn[592]: Packet is 840 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:49:03 Butikken tinc.vpn[592]: Packet is 842 seqs in the future, dropped (2) from Node4 (10.20.37.2 port 655)
Dec 10 15:49:04 Butikken tinc.vpn[592]: Packet is 843 seqs in the future, dropped (3) from Node4 (10.20.37.2 port 655)
Dec 10 15:49:05 Butikken tinc.vpn[592]: Packet is 845 seqs in the future, dropped (4) from Node4 (10.20.37.2 port 655)
Dec 10 15:49:05 Butikken tinc.vpn[592]: Lost 847 packets from Node4 (10.20.37.2 port 655)
Dec 10 15:49:33 Butikken tinc.vpn[592]: Got REQ_KEY from Node4 while we already started a SPTPS session!
Dec 10 15:49:59 Butikken tinc.vpn[592]: Got REQ_KEY from Node4 while we already started a SPTPS session!
Dec 10 15:50:10 Butikken tinc.vpn[592]: Packet is 149 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:10 Butikken tinc.vpn[592]: Invalid packet seqno: 154 != 0 from Node4 (10.20.37.2 port 655)
Dec 10 15:50:11 Butikken tinc.vpn[592]: Packet is 839 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:11 Butikken tinc.vpn[592]: Packet is 840 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:12 Butikken tinc.vpn[592]: Packet is 841 seqs in the future, dropped (2) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:12 Butikken tinc.vpn[592]: Packet is 843 seqs in the future, dropped (3) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:13 Butikken tinc.vpn[592]: Packet is 845 seqs in the future, dropped (4) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:13 Butikken tinc.vpn[592]: Lost 848 packets from Node4 (10.20.37.2 port 655)
Dec 10 15:50:34 Butikken tinc.vpn[592]: Packet is 839 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:45 Butikken tinc.vpn[592]: Packet is 840 seqs in the future, dropped (1) from Node4 (10.20.37.2 port 655)
Dec 10 15:50:45 Butikken tinc.vpn[592]: Invalid packet seqno: 866 != 0 from Node4 (10.20.37.2 port 655)
Dec 10 15:50:45 Butikken tinc.vpn[592]: Invalid packet seqno: 867 != 0 from Node4 (10.20.37.2 port 655)
Dec 10 15:50:45 Butikken tinc.vpn[592]: Invalid packet seqno: 868 != 0 from Node4 (10.20.37.2 port 655)

Node4
Dec 10 15:47:22 raspberrypi tinc.vpn[607]: Packet is 2685 seqs in the future, dropped (1) from Node2 (10.20.15.2 port 655)
Dec 10 15:47:53 raspberrypi tinc.vpn[607]: Packet is 142 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:47:53 raspberrypi tinc.vpn[607]: Invalid packet seqno: 7540 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:47:53 raspberrypi tinc.vpn[607]: Invalid packet seqno: 7541 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:47:53 raspberrypi tinc.vpn[607]: Invalid packet seqno: 7542 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:47:53 raspberrypi tinc.vpn[607]: Invalid packet seqno: 7543 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:48:30 raspberrypi tinc.vpn[607]: Packet is 638 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:30 raspberrypi tinc.vpn[607]: Invalid packet seqno: 660 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:48:31 raspberrypi tinc.vpn[607]: Packet is 839 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:31 raspberrypi tinc.vpn[607]: Packet is 840 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:32 raspberrypi tinc.vpn[607]: Packet is 841 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:33 raspberrypi tinc.vpn[607]: Packet is 843 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:33 raspberrypi tinc.vpn[607]: Packet is 846 seqs in the future, dropped (3) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:34 raspberrypi tinc.vpn[607]: Packet is 847 seqs in the future, dropped (4) from Node3 (10.20.25.2 port 655)
Dec 10 15:48:35 raspberrypi tinc.vpn[607]: Lost 849 packets from Node3 (10.20.25.2 port 655)
Dec 10 15:49:01 raspberrypi tinc.vpn[607]: Got REQ_KEY from Node3 while we already started a SPTPS session!
Dec 10 15:49:33 raspberrypi tinc.vpn[607]: Packet is 593 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:33 raspberrypi tinc.vpn[607]: Invalid packet seqno: 609 != 0 from Node3 (10.20.25.2 port 655)
Dec 10 15:49:36 raspberrypi tinc.vpn[607]: Packet is 839 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:36 raspberrypi tinc.vpn[607]: Packet is 840 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:36 raspberrypi tinc.vpn[607]: Packet is 841 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:37 raspberrypi tinc.vpn[607]: Packet is 843 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:39 raspberrypi tinc.vpn[607]: Packet is 845 seqs in the future, dropped (3) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:40 raspberrypi tinc.vpn[607]: Packet is 847 seqs in the future, dropped (4) from Node3 (10.20.25.2 port 655)
Dec 10 15:49:40 raspberrypi tinc.vpn[607]: Lost 850 packets from Node3 (10.20.25.2 port 655)
Dec 10 15:49:59 raspberrypi tinc.vpn[607]: Packet is 839 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:10 raspberrypi tinc.vpn[607]: Got REQ_KEY from Node3 while we already started a SPTPS session!
Dec 10 15:50:34 raspberrypi tinc.vpn[607]: Got REQ_KEY from Node3 while we already started a SPTPS session!
Dec 10 15:50:43 raspberrypi tinc.vpn[607]: Packet is 135 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:43 raspberrypi tinc.vpn[607]: Packet is 138 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:44 raspberrypi tinc.vpn[607]: Packet is 139 seqs in the future, dropped (3) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:44 raspberrypi tinc.vpn[607]: Packet is 140 seqs in the future, dropped (4) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:44 raspberrypi tinc.vpn[607]: Lost 142 packets from Node3 (10.20.25.2 port 655)
Dec 10 15:50:45 raspberrypi tinc.vpn[607]: Got REQ_KEY from Node3 while we already started a SPTPS session!
Dec 10 15:50:48 raspberrypi tinc.vpn[607]: Packet is 840 seqs in the future, dropped (1) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:48 raspberrypi tinc.vpn[607]: Packet is 841 seqs in the future, dropped (2) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:48 raspberrypi tinc.vpn[607]: Packet is 842 seqs in the future, dropped (3) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:49 raspberrypi tinc.vpn[607]: Packet is 843 seqs in the future, dropped (4) from Node3 (10.20.25.2 port 655)
Dec 10 15:50:49 raspberrypi tinc.vpn[607]: Lost 845 packets from Node3 (10.20.25.2 port 655)