Hi,
s6-init user here, but I don't think this is an s6-specific issue. I noticed that after every reboot I wasn't connected to my tailscale network - Tailscaled is set to launch on boot... A quick tailscale status
shows that I'm logged out. Stopping and restarting the tailscaled service via s6 does not trigger the logout, it's a reboot that's needed for this. There are issues like this already reported, see [1]. Thing is I'm on Linux, and I don't think the issue is with tailscale itself since I have another Archlinux setup that has not issues like these.
Some error lines are telling me it's looking for some systemd/systemd-resolved/dbus implementation that Artix-s6 could be lacking out of the box:
2024-05-01 21:43:16.030537525 2024/05/01 21:43:16 monitor: RTM_DELROUTE: src=, dst=fe80::/64, gw=, outif=6, table=254
2024-05-01 21:43:16.030550662 2024/05/01 21:43:16 monitor: RTM_DELROUTE: src=, dst=fe80::fc54:ff:fecf:fd79/128, gw=, outif=6, table=255
2024-05-01 21:43:16.030554199 2024/05/01 21:43:16 monitor: RTM_DELROUTE: src=, dst=ff00::/8, gw=, outif=6, table=255
2024-05-01 21:43:16.043048127 2024/05/01 21:43:16 LinkChange: major, rebinding. New state: interfaces.State{defaultRoute=wlan0 ifs={tailscale0:[100.64.0.8/32 fd7a:115c:a1e0::8/128 llu6] virbr0:[192.168.122.1/24] wlan0:[192.168.1.5/24]} v4=true v6=false}
2024-05-01 21:43:16.043054363 2024/05/01 21:43:16 dns: Set: {DefaultResolvers:[] Routes:{} SearchDomains:[] Hosts:4}
2024-05-01 21:43:16.043055588 2024/05/01 21:43:16 dns: Resolvercfg: {Routes:{} Hosts:4 LocalDomains:[]}
2024-05-01 21:43:16.043056629 2024/05/01 21:43:16 dns: OScfg: {}
2024-05-01 21:43:16.043057789 2024/05/01 21:43:16 wgengine: set DNS config again after major link change
2024-05-01 21:43:16.043122247 2024/05/01 21:43:16 onPortUpdate(port=59033, network=udp6)
2024-05-01 21:43:16.043182209 2024/05/01 21:43:16 onPortUpdate(port=39446, network=udp4)
2024-05-01 21:43:16.122588038 2024/05/01 21:43:16 Rebind; defIf="wlan0", ips=[192.168.1.5/24]
2024-05-01 21:43:16.122619734 2024/05/01 21:43:16 magicsock: 1 active derp conns: derp-18=cr16m0s,wr16m0s
2024-05-01 21:43:16.135891780 2024/05/01 21:43:16 portmapper: UPnP discovery response from non-UPnP port 60161
2024-05-01 21:43:16.176208824 2024/05/01 21:43:16 post-rebind ping of DERP region 18 okay
2024-05-01 21:43:16.374061415 2024/05/01 21:43:16 portmapper: UPnP meta changed: [{Location:http://192.168.1.1:49652/49652gatedesc.xml Server:Linux/4.4.240, UPnP/1.0, Portable SDK for UPnP devices/1.12.1 USN:uuid:00e0fc37-2525-2828-2500-C0E3FBA4EFCA::urn:schemas-upnp-org:device:InternetGatewayDevice:1}]
2024-05-01 21:43:16.374180606 2024/05/01 21:43:16 control: NetInfo: NetInfo{varies=false hairpin=false ipv6=false ipv6os=true udp=true icmpv4=false derp=#18 portmap=U link="" firewallmode="ipt-default"}
2024-05-01 21:43:16.379388568 2024/05/01 21:43:16 portmapper: saw UPnP type WANIPConnection1 at http://192.168.1.1:49652/49652gatedesc.xml; Huawei IGD (Huawei), method=single
2024-05-01 21:43:16.786617508 2024/05/01 21:43:16 control: NetInfo: NetInfo{varies=true hairpin=false ipv6=false ipv6os=true udp=true icmpv4=false derp=#18 portmap=active-U link="" firewallmode="ipt-default"}
2024-05-01 21:43:16.786663917 2024/05/01 21:43:16 magicsock: endpoints changed: <PUBLIC_IP_ADDRESS>:49756 (portmap), <PUBLIC_IP_ADDRESS>:39446 (stun), <PUBLIC_IP_ADDRESS>:54726 (portmap), 192.168.1.5:39446 (local), 192.168.122.1:39446 (local)
2024-05-01 21:43:42.488654749 2024/05/01 21:43:42 control: NetInfo: NetInfo{varies=false hairpin=false ipv6=false ipv6os=true udp=true icmpv4=false derp=#18 portmap=active-U link="" firewallmode="ipt-default"}
2024-05-01 21:43:56.622626990 2024/05/01 21:43:56 tailscaled got signal terminated; shutting down
2024-05-01 21:43:56.622635273 2024/05/01 21:43:56 control: client.Shutdown()
2024-05-01 21:43:56.622639271 2024/05/01 21:43:56 control: client.Shutdown
2024-05-01 21:43:56.622643038 2024/05/01 21:43:56 control: authRoutine: exiting
2024-05-01 21:43:56.622646839 2024/05/01 21:43:56 control: updateRoutine: exiting
2024-05-01 21:43:56.622650605 2024/05/01 21:43:56 control: mapRoutine: exiting
2024-05-01 21:43:56.622654207 2024/05/01 21:43:56 control: Client.Shutdown done.
2024-05-01 21:43:56.699790039 2024/05/01 21:43:56 magicsock: closing connection to derp-18 (conn-close), age 16m26s
2024-05-01 21:43:56.699801781 2024/05/01 21:43:56 magicsock: 0 active derp conns
2024-05-01 21:43:56.702467131 2024/05/01 21:43:56 monitor: RTM_DELROUTE: src=, dst=fe80::/64, gw=, outif=8, table=254
2024-05-01 21:43:56.704103647 2024/05/01 21:43:56 monitor: RTM_DELROUTE: src=, dst=fe80::a9bd:e0aa:6ac:53fb/128, gw=, outif=8, table=255
2024-05-01 21:43:56.704112741 2024/05/01 21:43:56 monitor: RTM_DELROUTE: src=, dst=ff00::/8, gw=, outif=8, table=255
2024-05-01 21:43:56.704121326 2024/05/01 21:43:56 monitor: ip rule deleted: {Family:2 DstLength:0 SrcLength:0 Tos:0 Table:254 Protocol:0 Scope:0 Type:1 Flags:0 Attributes:{Dst:<nil> Src:<nil> Gateway:<nil> OutIface:0 Priority:5210 Table:254 Mark:16711680 Pref:<nil> Expires:<nil> Metrics:<nil> Multipath:[]}}
2024-05-01 21:43:56.704128787 2024/05/01 21:43:56 monitor: ip rule deleted: {Family:2 DstLength:0 SrcLength:0 Tos:0 Table:253 Protocol:0 Scope:0 Type:1 Flags:0 Attributes:{Dst:<nil> Src:<nil> Gateway:<nil> OutIface:0 Priority:5230 Table:253 Mark:16711680 Pref:<nil> Expires:<nil> Metrics:<nil> Multipath:[]}}
2024-05-01 21:43:56.704133441 2024/05/01 21:43:56 monitor: ip rule deleted: {Family:2 DstLength:0 SrcLength:0 Tos:0 Table:0 Protocol:0 Scope:0 Type:7 Flags:0 Attributes:{Dst:<nil> Src:<nil> Gateway:<nil> OutIface:0 Priority:5250 Table:0 Mark:16711680 Pref:<nil> Expires:<nil> Metrics:<nil> Multipath:[]}}
2024-05-01 21:43:56.704137304 2024/05/01 21:43:56 monitor: ip rule deleted: {Family:2 DstLength:0 SrcLength:0 Tos:0 Table:52 Protocol:0 Scope:0 Type:1 Flags:0 Attributes:{Dst:<nil> Src:<nil> Gateway:<nil> OutIface:0 Priority:5270 Table:52 Mark:0 Pref:<nil> Expires:<nil> Metrics:<nil> Multipath:[]}}
2024-05-01 21:43:56.709743953 2024/05/01 21:43:56 monitor: RTM_DELROUTE: src=192.168.1.5/0, dst=192.168.1.255/32, gw=, outif=4, table=255
2024-05-01 21:43:56.709757496 2024/05/01 21:43:56 monitor: RTM_DELROUTE: src=192.168.1.5/0, dst=192.168.1.5/32, gw=, outif=4, table=255
2024-05-01 21:43:56.709762675 2024/05/01 21:43:56 [RATELIMIT] format("monitor: %s: src=%v, dst=%v, gw=%v, outif=%v, table=%v")
2024-05-01 21:43:56.844272340 2024/05/01 21:43:56 flushing log.
2024-05-01 21:43:56.844527082 2024/05/01 21:43:56 logger closing down
2024-05-01 21:44:26.119413814 2024/05/01 21:44:26 You have disabled logging. Tailscale will not be able to provide support.
2024-05-01 21:44:26.119543180 2024/05/01 21:44:26 logtail started
2024-05-01 21:44:26.119594849 2024/05/01 21:44:26 Program starting: v1.64.0, Go 1.22.2: []string{"tailscaled", "--state=/var/lib/tailscale/tailscaled.state", "--socket=/run/tailscale/tailscaled.sock", "--port", "0", "--no-logs-no-support"}
2024-05-01 21:44:26.120401118 2024/05/01 21:44:26 LogID: <very_long_random_log_identifier>
2024-05-01 21:44:26.120460334 2024/05/01 21:44:26 logpolicy: using system state directory "/var/lib/tailscale"
2024-05-01 21:44:26.120645321 2024/05/01 21:44:26 dns: ResolvConfMode error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.120706129 2024/05/01 21:44:26 dns: [rc=nm resolved=nss nm-resolved=yes nm=no resolv-conf-mode=error ret=systemd-resolved]
2024-05-01 21:44:26.120756271 2024/05/01 21:44:26 dns: using "systemd-resolved" mode
2024-05-01 21:44:26.120851474 2024/05/01 21:44:26 creating dns cleanup: route ip+net: no such network interface
2024-05-01 21:44:26.181429888 2024/05/01 21:44:26 deleting [-j ts-input] in filter/INPUT: running [/usr/bin/iptables -t filter -D INPUT -j ts-input --wait]: exit status 2: iptables v1.8.10 (legacy): Couldn't load target `ts-input':No such file or directory
2024-05-01 21:44:26.181441660
2024-05-01 21:44:26.181454814 Try 'iptables -h' or 'iptables --help' for more information.
2024-05-01 21:44:26.182745818 2024/05/01 21:44:26 deleting [-j ts-forward] in filter/FORWARD: running [/usr/bin/iptables -t filter -D FORWARD -j ts-forward --wait]: exit status 2: iptables v1.8.10 (legacy): Couldn't load target `ts-forward':No such file or directory
2024-05-01 21:44:26.182752633
2024-05-01 21:44:26.182757044 Try 'iptables -h' or 'iptables --help' for more information.
2024-05-01 21:44:26.292664366 2024/05/01 21:44:26 deleting [-j ts-postrouting] in nat/POSTROUTING: running [/usr/bin/iptables -t nat -D POSTROUTING -j ts-postrouting --wait]: exit status 2: iptables v1.8.10 (legacy): Couldn't load target `ts-postrouting':No such file or directory
2024-05-01 21:44:26.292672754
2024-05-01 21:44:26.292677463 Try 'iptables -h' or 'iptables --help' for more information.
2024-05-01 21:44:26.330108670 2024/05/01 21:44:26 deleting [-j ts-input] in filter/INPUT: running [/usr/bin/ip6tables -t filter -D INPUT -j ts-input --wait]: exit status 2: ip6tables v1.8.10 (legacy): Couldn't load target `ts-input':No such file or directory
2024-05-01 21:44:26.330116261
2024-05-01 21:44:26.330120103 Try `ip6tables -h' or 'ip6tables --help' for more information.
2024-05-01 21:44:26.331350079 2024/05/01 21:44:26 deleting [-j ts-forward] in filter/FORWARD: running [/usr/bin/ip6tables -t filter -D FORWARD -j ts-forward --wait]: exit status 2: ip6tables v1.8.10 (legacy): Couldn't load target `ts-forward':No such file or directory
2024-05-01 21:44:26.331356846
2024-05-01 21:44:26.331361301 Try `ip6tables -h' or 'ip6tables --help' for more information.
2024-05-01 21:44:26.331376025 2024/05/01 21:44:26 [RATELIMIT] format("deleting %v in %s/%s: %v")
2024-05-01 21:44:26.381970451 2024/05/01 21:44:26 wgengine.NewUserspaceEngine(tun "tailscale0") ...
2024-05-01 21:44:26.398536726 2024/05/01 21:44:26 dns: ResolvConfMode error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.398553201 2024/05/01 21:44:26 dns: [rc=nm resolved=nss nm-resolved=yes nm=no resolv-conf-mode=error ret=systemd-resolved]
2024-05-01 21:44:26.398580247 2024/05/01 21:44:26 dns: using "systemd-resolved" mode
2024-05-01 21:44:26.399806101 2024/05/01 21:44:26 dns: dbus connection error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.401198712 2024/05/01 21:44:26 dns: using *dns.resolvedManager
2024-05-01 21:44:26.401228659 2024/05/01 21:44:26 link state: interfaces.State{defaultRoute= ifs={} v4=false v6=false}
2024-05-01 21:44:26.401519519 2024/05/01 21:44:26 onPortUpdate(port=40259, network=udp6)
2024-05-01 21:44:26.401543179 2024/05/01 21:44:26 router: using firewall mode pref
2024-05-01 21:44:26.401554635 2024/05/01 21:44:26 router: default choosing iptables
2024-05-01 21:44:26.406273840 2024/05/01 21:44:26 dns: dbus connection error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.415013755 2024/05/01 21:44:26 router: v6 = true, v6filter = true, v6nat = true
2024-05-01 21:44:26.415022068 2024/05/01 21:44:26 onPortUpdate(port=58887, network=udp4)
2024-05-01 21:44:26.415024938 2024/05/01 21:44:26 magicsock: disco key = d:7c91808a4a29c523
2024-05-01 21:44:26.415027380 2024/05/01 21:44:26 magicsock: SetNetworkUp(false)
2024-05-01 21:44:26.415029720 2024/05/01 21:44:26 Creating WireGuard device...
2024-05-01 21:44:26.415043383 2024/05/01 21:44:26 Bringing WireGuard device up...
2024-05-01 21:44:26.415783975 2024/05/01 21:44:26 Bringing router up...
2024-05-01 21:44:26.421370524 2024/05/01 21:44:26 external route: up
2024-05-01 21:44:26.421517658 2024/05/01 21:44:26 Clearing router settings...
2024-05-01 21:44:26.421562629 2024/05/01 21:44:26 Starting network monitor...
2024-05-01 21:44:26.421613723 2024/05/01 21:44:26 Engine created.
2024-05-01 21:44:26.423769569 2024/05/01 21:44:26 pm: migrating "_daemon" profile to new format
2024-05-01 21:44:26.426500151 2024/05/01 21:44:26 envknob: TS_NO_LOGS_NO_SUPPORT="true"
2024-05-01 21:44:26.426507745 2024/05/01 21:44:26 logpolicy: using system state directory "/var/lib/tailscale"
2024-05-01 21:44:26.426561041 2024/05/01 21:44:26 got LocalBackend in 45ms
2024-05-01 21:44:26.426605118 2024/05/01 21:44:26 Start
2024-05-01 21:44:26.450109370 2024/05/01 21:44:26 Backend: logs: be:<very_long_random_logs_identifier> fe:
2024-05-01 21:44:26.450373668 2024/05/01 21:44:26 control: setPaused(true)
2024-05-01 21:44:26.450494993 2024/05/01 21:44:26 control: mapRoutine: awaiting unpause
2024-05-01 21:44:26.450959616 2024/05/01 21:44:26 control: authRoutine: awaiting unpause
2024-05-01 21:44:26.451002731 2024/05/01 21:44:26 control: updateRoutine: awaiting unpause
2024-05-01 21:44:26.451038889 2024/05/01 21:44:26 Switching ipn state NoState -> NeedsLogin (WantRunning=false, nm=false)
2024-05-01 21:44:26.451105340 2024/05/01 21:44:26 blockEngineUpdates(true)
2024-05-01 21:44:26.451225433 2024/05/01 21:44:26 health("overall"): error: state=NeedsLogin, wantRunning=false
2024-05-01 21:44:26.451784770 2024/05/01 21:44:26 wgengine: Reconfig: configuring userspace WireGuard config (with 0/0 peers)
2024-05-01 21:44:26.451860301 2024/05/01 21:44:26 wgengine: Reconfig: configuring router
2024-05-01 21:44:26.451909579 2024/05/01 21:44:26 wgengine: Reconfig: configuring DNS
2024-05-01 21:44:26.451964725 2024/05/01 21:44:26 dns: Set: {DefaultResolvers:[] Routes:{} SearchDomains:[] Hosts:0}
2024-05-01 21:44:26.452008804 2024/05/01 21:44:26 dns: Resolvercfg: {Routes:{} Hosts:0 LocalDomains:[]}
2024-05-01 21:44:26.452047243 2024/05/01 21:44:26 dns: OScfg: {}
2024-05-01 21:44:26.452105831 2024/05/01 21:44:26 dns: failed to configure resolved: resolved DBus does not have a connection
2024-05-01 21:44:26.452224623 2024/05/01 21:44:26 Reconfig(down): resolved DBus does not have a connection
2024-05-01 21:44:26.452360173 2024/05/01 21:44:26 health("dns-os"): error: resolved DBus does not have a connection
2024-05-01 21:44:26.452471736 2024/05/01 21:44:26 health("dns"): error: resolved DBus does not have a connection
2024-05-01 21:44:26.458509119 2024/05/01 21:44:26 dns: dbus connection error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.562690641 2024/05/01 21:44:26 dns: dbus connection error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.727739496 2024/05/01 21:44:26 dns: dbus connection error: dial unix /var/run/dbus/system_bus_socket: connect: no such file or directory
2024-05-01 21:44:26.727842321 2024/05/01 21:44:26 [RATELIMIT] format("dns: dbus connection error: %v")
2024-05-01 21:44:30.889412418 2024/05/01 21:44:30 health("dns-os"): ok
2024-05-01 21:44:32.630551092 2024/05/01 21:44:32 monitor: RTM_DELROUTE: src=, dst=ff00::/8, gw=, outif=4, table=255
2024-05-01 21:44:32.635023894 2024/05/01 21:44:32 monitor: RTM_NEWROUTE: src=, dst=192.168.1.1/32, gw=, outif=4, table=254
2024-05-01 21:44:32.635029949 2024/05/01 21:44:32 monitor: RTM_NEWROUTE: src=, dst=, gw=192.168.1.1, outif=4, table=254
2024-05-01 21:44:32.749614193 2024/05/01 21:44:32 monitor: RTM_NEWROUTE: src=192.168.1.5/0, dst=192.168.1.5/32, gw=, outif=4, table=255
2024-05-01 21:44:32.749622809 2024/05/01 21:44:32 monitor: RTM_NEWROUTE: src=192.168.1.5/0, dst=192.168.1.255/32, gw=, outif=4, table=255
2024-05-01 21:44:32.749624086 2024/05/01 21:44:32 [RATELIMIT] format("monitor: %s: src=%v, dst=%v, gw=%v, outif=%v, table=%v")
2024-05-01 21:44:32.882827882 2024/05/01 21:44:32 LinkChange: major, rebinding. New state: interfaces.State{defaultRoute=wlan0 ifs={wlan0:[192.168.1.5/24]} v4=true v6=false}
2024-05-01 21:44:32.882914362 2024/05/01 21:44:32 magicsock: SetNetworkUp(true)
2024-05-01 21:44:32.882970145 2024/05/01 21:44:32 dns: Set: {DefaultResolvers:[] Routes:{} SearchDomains:[] Hosts:0}
2024-05-01 21:44:32.883018891 2024/05/01 21:44:32 dns: Resolvercfg: {Routes:{} Hosts:0 LocalDomains:[]}
2024-05-01 21:44:32.883094331 2024/05/01 21:44:32 dns: OScfg: {}
2024-05-01 21:44:32.883195086 2024/05/01 21:44:32 control: setPaused(false)
2024-05-01 21:44:32.884397701 2024/05/01 21:44:32 dns: failed to configure resolved: setLinkDNS: The name org.freedesktop.resolve1 was not provided by any .service files
2024-05-01 21:44:32.884519407 2024/05/01 21:44:32 wgengine: error setting DNS config after major link change: setLinkDNS: The name org.freedesktop.resolve1 was not provided by any .service files
2024-05-01 21:44:32.884610666 2024/05/01 21:44:32 health("dns-os"): error: setLinkDNS: The name org.freedesktop.resolve1 was not provided by any .service files
2024-05-01 21:44:32.884859518 2024/05/01 21:44:32 onPortUpdate(port=40259, network=udp6)
2024-05-01 21:44:32.885164509 2024/05/01 21:44:32 onPortUpdate(port=58887, network=udp4)
2024-05-01 21:44:32.885319658 2024/05/01 21:44:32 Rebind; defIf="wlan0", ips=[192.168.1.5/24]
2024-05-01 21:44:32.885415433 2024/05/01 21:44:32 magicsock: 0 active derp conns
2024-05-01 21:44:32.886251269 2024/05/01 21:44:32 monitor: gateway and self IP changed: gw=192.168.1.1 self=192.168.1.5
To be more specific and if I'm not mistaken it tries to check the DNS, tells itself that NetworkManager is not used (it is), falls back to systemd-resolved for this, tries some iptables trickery and fails miserably. The DBUS connection fails as well (it's running as a service on boot), and there's no org.freedesktop.resolve1. I hope there is no hardcoded systemd dependency and a workaround can be provided.
Hope I provided everything needed for now.
Topic: Tailscale - New MachineID after Reboot - Reauth Needed and Logged out Repeatedly (Read 352 times)
previous topic - next topic