Ich hab’s auch gerade live erleben dürfen, nachdem ich im Smokeping der VM hinter der Mesh04-Gluon-VM das Muster gefunden und das Remotelogging gefixt hatte:
(Zeiten in UTC)
MTRs vom Knoten zum Gateway und vom Clieent (durch das GW) zu 1.1.1.1:
33378-test-20240502 (192.168.177.14) 2024-05-20T01:30:05+0200
Keys: Help Display mode Restart statistics Order of fields quit
Packets Pings
Host Loss% Snt Last Avg Best Wrst StDev
1. 192.168.177.1 0.0% 1370 1.2 0.7 0.6 12.3 0.4
2. p3e9bf2e7.dip0.t-ipconnect.de 0.0% 1370 7.5 10.1 7.4 74.6 7.5
3. b-eh2-i.B.DE.NET.DTAG.DE 0.0% 1370 13.8 14.0 13.0 65.6 3.7
4. bgp-ber01.4830.org 10.9% 1370 14.0 14.2 13.6 62.6 3.2
5. ngw-ber01.4830.org 0.5% 1370 13.7 14.1 13.6 41.0 2.8
My traceroute [v0.95]
test-mesh02 (10.234.147.250) -> 1.1.1.1 (1.1.1.1) 2024-05-19T23:31:06+0000
Keys: Help Display mode Restart statistics Order of fields quit
Packets Pings
Host Loss% Snt Last Avg Best Wrst StDev
1. 10.234.144.70 2.0% 1436 22.2 23.3 22.0 48.5 3.3
2. 194.113.54.65 86.7% 1436 43.2 24.0 22.0 44.4 2.9
3. wobcom.n15.community-ix.de 2.6% 1436 24.4 24.6 22.7 71.1 4.2
4. et-0-0-2-0.cbrc1101.as9136.net 1.5% 1436 24.2 25.3 22.9 107.8 5.5
5. cloudflare.bcix.de 3.1% 1436 31.9 29.6 22.9 117.1 11.3
6. one.one.one.one 2.9% 1436 23.7 24.7 22.7 72.9 4.2
Logs via remote logging:
May 20 00:57:10 33378-test-20240502 netifd: wan (2926): udhcpc: lease of 192.168.177.14 obtained, lease time 600
May 20 00:57:45 33378-test-20240502 netifd: client (2940): cat: write error: Broken pipe
May 20 00:59:59 33378-test-20240502 netifd: client (2940): cat: write error: Broken pipe
May 20 01:00:23 33378-test-20240502 netifd: client (2940): cat: write error: Broken pipe
May 20 01:01:01 33378-test-20240502 netifd: client (2940): cat: write error: Broken pipe
May 20 01:02:10 33378-test-20240502 : autoupdater-wifi-fallback: connectivity check failed
May 20 01:02:10 33378-test-20240502 : autoupdater-wifi-fallback: going to fallback mode
May 20 01:02:10 33378-test-20240502 : autoupdater-wifi-fallback: going back to standard mode
May 20 01:02:10 33378-test-20240502 netifd: Interface 'bat0' is now down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'loopback' is now down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'loopback' is disabled
May 20 01:02:10 33378-test-20240502 netifd: Network device 'lo' link is down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'loopback' has link connectivity loss
May 20 01:02:10 33378-test-20240502 dnsmasq[1767]: no servers found in /tmp/resolv.conf.auto, will retry
May 20 01:02:10 33378-test-20240502 kernel: [1477775.080263] batman_adv: bat0: Interface deactivated: primary0
May 20 01:02:10 33378-test-20240502 kernel: [1477775.082326] batman_adv: bat0: Removing interface: primary0
May 20 01:02:10 33378-test-20240502 kernel: [1477775.084741] batman_adv: bat0: Interface deactivated: mesh-vpn
May 20 01:02:10 33378-test-20240502 kernel: [1477775.086576] batman_adv: bat0: Removing interface: mesh-vpn
May 20 01:02:10 33378-test-20240502 kernel: [1477775.089627] br-client: port 2(bat0) entered disabled state
May 20 01:02:10 33378-test-20240502 netifd: Network device 'bat0' link is down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'bat0' has link connectivity loss
May 20 01:02:10 33378-test-20240502 netifd: Interface 'client' is now down
May 20 01:02:10 33378-test-20240502 kernel: [1477775.137227] device bat0 left promiscuous mode
May 20 01:02:10 33378-test-20240502 kernel: [1477775.138743] br-client: port 2(bat0) entered disabled state
May 20 01:02:10 33378-test-20240502 kernel: [1477775.142172] br-client: port 1(eth0) entered disabled state
May 20 01:02:10 33378-test-20240502 netifd: Interface 'bat0' is disabled
May 20 01:02:10 33378-test-20240502 kernel: [1477775.205729] device eth0 left promiscuous mode
May 20 01:02:10 33378-test-20240502 kernel: [1477775.207360] br-client: port 1(eth0) entered disabled state
May 20 01:02:10 33378-test-20240502 kernel: [1477775.214720] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
May 20 01:02:10 33378-test-20240502 netifd: Interface 'client' is disabled
May 20 01:02:10 33378-test-20240502 netifd: Interface 'gluon_bat0' is now down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'mesh_vpn' is now down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'mesh_vpn' is disabled
May 20 01:02:10 33378-test-20240502 netifd: Network device 'eth0' link is down
May 20 01:02:10 33378-test-20240502 netifd: bridge 'br-client' link is down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'client' has link connectivity loss
May 20 01:02:10 33378-test-20240502 netifd: Network device 'mesh-vpn' link is down
May 20 01:02:10 33378-test-20240502 netifd: Interface 'mesh_vpn' has link connectivity loss
May 20 01:02:10 33378-test-20240502 netifd: wan (2926): udhcpc: sending renew to 192.168.177.9
May 20 01:02:10 33378-test-20240502 netifd: wan (2926): udhcpc: received SIGTERM
May 20 01:02:10 33378-test-20240502 netifd: Interface 'wan' is now down
May 20 01:02:12 33378-test-20240502 logread[1715]: Logread connected to 192.168.177.9:514
May 20 01:02:13 33378-test-20240502 odhcp6c[15979]: Failed to send DHCPV6 message to ff02::1:2 (Address not available)
May 20 01:02:13 33378-test-20240502 odhcp6c[15981]: Failed to send DHCPV6 message to ff02::1:2 (Address not available)
May 20 01:02:13 33378-test-20240502 odhcp6c[15981]: Failed to send DHCPV6 message to ff02::1:2 (Address not available)
May 20 01:02:15 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:16 33378-test-20240502 netifd: Interface 'wan6' is now up
May 20 01:02:16 33378-test-20240502 firewall: Reloading firewall due to ifup of wan6 (br-wan)
May 20 01:02:16 33378-test-20240502 dnsmasq[2613]: reading /var/gluon/wan-dnsmasq/resolv.conf
May 20 01:02:16 33378-test-20240502 dnsmasq[2613]: using nameserver fd00::9a9b:cbff:feb1:1397#53
May 20 01:02:16 33378-test-20240502 dnsmasq[2613]: using nameserver 2003:ef:9f17:8900:9a9b:cbff:feb1:1397#53
May 20 01:02:16 33378-test-20240502 dnsmasq[2613]: using nameserver 192.168.177.9#53
May 20 01:02:16 33378-test-20240502 dnsmasq[2613]: using nameserver 192.168.177.1#53
May 20 01:02:16 33378-test-20240502 dnsmasq[2613]: using nameserver 8.8.8.8#53
May 20 01:02:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:22 33378-test-20240502 kernel: [1477786.797900] batman_adv: bat0: IGMP Querier appeared
May 20 01:02:22 33378-test-20240502 kernel: [1477786.799860] batman_adv: bat0: MLD Querier appeared
May 20 01:02:27 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:33 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:39 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:45 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:51 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:02:57 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:03:03 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:03:09 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=5)!
May 20 01:03:10 33378-test-20240502 td-client: [g02m04.4830.org:10004] Tunnel has timed out, closing down interface.
May 20 01:03:10 33378-test-20240502 td-client: [g02m04.4830.org:10004] Failed to send() control packet (errno=19, type=3)!
May 20 01:03:10 33378-test-20240502 netifd: Network device 'mesh-vpn' link is down
May 20 01:03:10 33378-test-20240502 netifd: Interface 'mesh_vpn' has link connectivity loss
May 20 01:03:10 33378-test-20240502 kernel: [1477834.469730] batman_adv: bat0: Interface deactivated: mesh-vpn
May 20 01:03:10 33378-test-20240502 kernel: [1477834.485739] batman_adv: bat0: Removing interface: mesh-vpn
May 20 01:03:10 33378-test-20240502 td-client: [g02m04.4830.org:10004] Connection lost.
May 20 01:03:10 33378-test-20240502 td-client: for(;;) -- context_reinitialize() for all brokers.
May 20 01:03:10 33378-test-20240502 netifd: Interface 'mesh_vpn' is now down
May 20 01:03:10 33378-test-20240502 netifd: Interface 'mesh_vpn' is disabled
May 20 01:03:10 33378-test-20240502 td-client: Performing broker selection...
May 20 01:03:10 33378-test-20240502 td-client: Brokers deemed working: 6
May 20 01:03:10 33378-test-20240502 td-client: main loop -- Reset availability information and standby setting.
May 20 01:03:10 33378-test-20240502 td-client: [g01m04.4830.org:10004] is not flagged as broken, that's good.
May 20 01:03:10 33378-test-20240502 td-client: [g02m04.4830.org:10004] is not flagged as broken, that's good.
May 20 01:03:10 33378-test-20240502 td-client: [g03m04.4830.org:10004] is not flagged as broken, that's good.
May 20 01:03:10 33378-test-20240502 td-client: [g04m04.4830.org:10004] is not flagged as broken, that's good.
May 20 01:03:10 33378-test-20240502 td-client: [g05m04.4830.org:10004] is not flagged as broken, that's good.
May 20 01:03:10 33378-test-20240502 td-client: [g06m04.4830.org:10004] is not flagged as broken, that's good.
May 20 01:03:10 33378-test-20240502 td-client: main loop -- Perform broker processing for 10 seconds or until all brokers are ready.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] Broker's FQDN has been resolved to 194.113.55.67.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_GET_USAGE, sending initial request.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] Broker's FQDN has been resolved to 194.113.54.80.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_GET_USAGE, sending initial request.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] Broker usage: 384
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] Broker usage: 448
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] Broker's FQDN has been resolved to 194.113.54.81.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_GET_USAGE, sending initial request.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] Broker's FQDN has been resolved to 194.113.55.71.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_GET_USAGE, sending initial request.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] Broker usage: 384
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] Broker usage: 384
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:11 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g03m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g04m04.4830.org:10004] is ready.
May 20 01:03:11 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:11 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
[…]
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_REINIT.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] reinitializing tunnel context.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] Failed to resolve hostname.
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] Failed to resolve hostname.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_REINIT.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] reinitializing tunnel context.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is in STATE_STANDBY
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_REINIT.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] reinitializing tunnel context.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] Broker's FQDN hasn't been resolved to an IP yet.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is in STATE_RESOLVING
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] Failed to resolve hostname.
May 20 01:03:21 33378-test-20240502 td-client: [g01m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g03m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g04m04.4830.org:10004] is ready.
May 20 01:03:21 33378-test-20240502 td-client: [g05m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: [g06m04.4830.org:10004] is NOT ready.
May 20 01:03:21 33378-test-20240502 td-client: main loop -- select_broker()
May 20 01:03:21 33378-test-20240502 td-client: Selected g02m04.4830.org:10004 as the best broker.
May 20 01:03:21 33378-test-20240502 td-client: main loop -- activate selected broker
May 20 01:03:21 33378-test-20240502 td-client: main loop -- Perform processing on the main context ...
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_GET_COOKIE
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] sending request for a tasty cookie ...
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] is in STATE_GET_TUNNEL
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] sending tunnel setup request ...
May 20 01:03:21 33378-test-20240502 netifd: Interface 'mesh_vpn' is enabled
May 20 01:03:21 33378-test-20240502 netifd: Network device 'mesh-vpn' link is up
May 20 01:03:21 33378-test-20240502 netifd: Interface 'mesh_vpn' has link connectivity
May 20 01:03:21 33378-test-20240502 netifd: Interface 'mesh_vpn' is setting up now
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] Tunnel successfully established.
May 20 01:03:21 33378-test-20240502 td-client: [g02m04.4830.org:10004] Setting MTU to 1364
May 20 01:03:21 33378-test-20240502 netifd: Interface 'mesh_vpn' is now up
May 20 01:03:21 33378-test-20240502 kernel: [1477845.462835] batman_adv: bat0: Adding interface: mesh-vpn
May 20 01:03:21 33378-test-20240502 kernel: [1477845.465202] batman_adv: bat0: The MTU of interface mesh-vpn is too small (1364) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1532 would solve the problem.
May 20 01:03:21 33378-test-20240502 kernel: [1477845.477372] batman_adv: bat0: Interface activated: mesh-vpn
May 20 01:03:21 33378-test-20240502 firewall: Reloading firewall due to ifup of mesh_vpn (mesh-vpn)
May 20 01:03:22 33378-test-20240502 gluon-radv-filterd[16072]: Switching to f2:be:ef:00:04:71 (TQ=0)
May 20 01:03:29 33378-test-20240502 netifd: Interface 'client' is now up
May 20 01:03:29 33378-test-20240502 dnsmasq[1767]: reading /tmp/resolv.conf.auto
May 20 01:03:29 33378-test-20240502 dnsmasq[1767]: using local addresses only for domain lan
May 20 01:03:29 33378-test-20240502 dnsmasq[1767]: using nameserver 2620:fe::9#53
May 20 01:03:29 33378-test-20240502 dnsmasq[1767]: using nameserver 2606:4700:4700::1112#53
May 20 01:03:29 33378-test-20240502 firewall: Reloading firewall due to ifup of client (br-client)
May 20 01:03:37 33378-test-20240502 td-client: main loop -- connection established, broker [g02m04.4830.org:10004] is fine.
May 20 01:04:13 33378-test-20240502 netifd: client (15981): cat: write error: Broken pipe
May 20 01:06:19 33378-test-20240502 netifd: client (15981): cat: write error: Broken pipe
May 20 01:07:11 33378-test-20240502 netifd: wan (15971): udhcpc: sending renew to 192.168.177.9
May 20 01:07:11 33378-test-20240502 netifd: wan (15971): udhcpc: lease of 192.168.177.14 obtained, lease time 600
May 20 01:07:11 33378-test-20240502 firewall: Reloading firewall due to ifupdate of wan (br-wan)
May 20 01:07:48 33378-test-20240502 netifd: client (15981): cat: write error: Broken pipe
May 20 01:08:29 33378-test-20240502 netifd: client (15981): cat: write error: Broken pipe
Problem 1:
May 20 01:02:10 33378-test-20240502 : autoupdater-wifi-fallback: connectivity check failed
May 20 01:02:10 33378-test-20240502 : autoupdater-wifi-fallback: going to fallback mode
May 20 01:02:10 33378-test-20240502 : autoupdater-wifi-fallback: going back to standard mode
Das dürfte nicht passieren, a) nicht der »connectivity check failed«, b) nicht »going to fallback mode« & »going back to standard mode« direkt nacheinander.
Problem 2:
May 20 01:03:10 33378-test-20240502 td-client: main loop -- Perform broker processing for 10 seconds or until all brokers are ready.
In den meisten Meshes haben wir 2 GWs mehr in der Firmware hinterlegt als tatsächlich vorbereitet/genutzt. Hier in Mesh 4 sind z. B. g05m04.4830.org und g06m04.4830.org nicht im DNS eingetragen. Heißt bei Problemen mit dem Tunnel gibt es ggf. immer <= 10 Strafsekunden
Zu Problem 1: das lua-Skript /usr/sbin/autoupdater-wifi-fallback
testet erst, ob eines der Batman-Gateways via batman-ping (»nur ein Ping«) erreichbar ist. Falls nicht(!), wird versucht, den/die eingetragenen Updateserver per ICMP-Ping (»nur ein einziges Ping!«) zu erreichen — schlägt das auch(!) fehl, wird der Katastrophenfall ausgerufen.
Insofern kann …
… tatsächlich effektiv zur Problemlösung beitragen. Reallocating resources …