Recall45:
error: You are logged out. The last login error was: fetch control key: Get "https://controlplane.tailscale.com/key?v=104": context canceled
2024-08-30T22:38:41.593 app[e825d66b7296e8] lax [info] 2024/08/30 22:38:41 control: control server key from https://controlplane.tailscale.com: ts2021=[fSeS+], legacy=[nlFWp]
refreshed the keys. thanks for spotting the silly error but the machine still exits right after creation - deployment and launch successful. Any clues?
Waiting for logs...
2024-08-31T02:21:23.229 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 dns: using "direct" mode
2024-08-31T02:21:23.229 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 dns: using *dns.directManager
2024-08-31T02:21:23.251 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 wgengine.NewUserspaceEngine(tun "tailscale0") ...
2024-08-31T02:21:23.257 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 dns: [rc=unknown ret=direct]
2024-08-31T02:21:23.257 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 dns: using "direct" mode
2024-08-31T02:21:23.257 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 dns: using *dns.directManager
2024-08-31T02:21:23.257 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 link state: interfaces.State{defaultRoute=eth0 ifs={eth0:[172.19.12.202/29 172.19.12.203/29 2605:4c40:104:6f43:0:ff0c:629f:1/127 fdaa:9:4fd:a7b:2b4:ff0c:629f:2/112 llu6]} v4=true v6=true}
2024-08-31T02:21:23.257 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 onPortUpdate(port=39920, network=udp6)
2024-08-31T02:21:23.258 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 router: using firewall mode pref
2024-08-31T02:21:23.258 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 router: default choosing iptables
2024-08-31T02:21:23.263 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 router: netfilter running in iptables mode v6 = true, v6filter = true, v6nat = true
2024-08-31T02:21:23.263 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 onPortUpdate(port=51337, network=udp4)
2024-08-31T02:21:23.263 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 magicsock: disco key = d:9d723c363c86d649
2024-08-31T02:21:23.263 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Creating WireGuard device...
2024-08-31T02:21:23.264 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Bringing WireGuard device up...
2024-08-31T02:21:23.264 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Bringing router up...
2024-08-31T02:21:23.264 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Clearing router settings...
2024-08-31T02:21:23.264 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Starting network monitor...
2024-08-31T02:21:23.264 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Engine created.
2024-08-31T02:21:23.266 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 external route: up
2024-08-31T02:21:23.269 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 monitor: [unexpected] network state changed, but stringification didn't: interfaces.State{defaultRoute=eth0 ifs={eth0:[172.19.12.202/29 172.19.12.203/29 2605:4c40:104:6f43:0:ff0c:629f:1/127 fdaa:9:4fd:a7b:2b4:ff0c:629f:2/112 llu6]} v4=true v6=true}
2024-08-31T02:21:23.269 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 monitor: [unexpected] old: {"InterfaceIPs":{"dummy0":null,"eth0":["172.19.12.202/29","172.19.12.203/29","2605:4c40:104:6f43:0:ff0c:629f:1/127","fdaa:9:4fd:a7b:2b4:ff0c:629f:2/112","fe80::dcad:4aff:feba:cd84/64"],"lo":["127.0.0.1/8","::1/128"],"teql0":null},"Interface":{"dummy0":{"Index":2,"MTU":1500,"Name":"dummy0","HardwareAddr":"EmTr7qdl","Flags":2,"AltAddrs":null,"Desc":""},"eth0":{"Index":3,"MTU":1420,"Name":"eth0","HardwareAddr":"3q1Kus2E","Flags":51,"AltAddrs":null,"Desc":""},"lo":{"Index":1,"MTU":65536,"Name":"lo","HardwareAddr":null,"Flags":37,"AltAddrs":null,"Desc":""},"teql0":{"Index":4,"MTU":1500,"Name":"teql0","HardwareAddr":null,"Flags":0,"AltAddrs":null,"Desc":""}},"HaveV6":true,"HaveV4":true,"IsExpensive":false,"DefaultRouteInterface":"eth0","HTTPProxy":"","PAC":""}
2024-08-31T02:21:23.270 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 monitor: [unexpected] new: {"InterfaceIPs":{"dummy0":null,"eth0":["172.19.12.202/29","172.19.12.203/29","2605:4c40:104:6f43:0:ff0c:629f:1/127","fdaa:9:4fd:a7b:2b4:ff0c:629f:2/112","fe80::dcad:4aff:feba:cd84/64"],"lo":["127.0.0.1/8","::1/128"],"tailscale0":["fe80::5f0c:2ed5:653e:2083/64"],"teql0":null},"Interface":{"dummy0":{"Index":2,"MTU":1500,"Name":"dummy0","HardwareAddr":"EmTr7qdl","Flags":2,"AltAddrs":null,"Desc":""},"eth0":{"Index":3,"MTU":1420,"Name":"eth0","HardwareAddr":"3q1Kus2E","Flags":51,"AltAddrs":null,"Desc":""},"lo":{"Index":1,"MTU":65536,"Name":"lo","HardwareAddr":null,"Flags":37,"AltAddrs":null,"Desc":""},"tailscale0":{"Index":5,"MTU":1280,"Name":"tailscale0","HardwareAddr":null,"Flags":57,"AltAddrs":null,"Desc":""},"teql0":{"Index":4,"MTU":1500,"Name":"teql0","HardwareAddr":null,"Flags":0,"AltAddrs":null,"Desc":""}},"HaveV6":true,"HaveV4":true,"IsExpensive":false,"DefaultRouteInterface":"eth0","HTTPProxy":"","PAC":""}
2024-08-31T02:21:23.270 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 LinkChange: major, rebinding. New state: interfaces.State{defaultRoute=eth0 ifs={eth0:[172.19.12.202/29 172.19.12.203/29 2605:4c40:104:6f43:0:ff0c:629f:1/127 fdaa:9:4fd:a7b:2b4:ff0c:629f:2/112 llu6]} v4=true v6=true}
2024-08-31T02:21:23.270 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 onPortUpdate(port=39920, network=udp6)
2024-08-31T02:21:23.270 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 onPortUpdate(port=51337, network=udp4)
2024-08-31T02:21:23.270 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Rebind; defIf="eth0", ips=[172.19.12.202/29 172.19.12.203/29 2605:4c40:104:6f43:0:ff0c:629f:1/127 fdaa:9:4fd:a7b:2b4:ff0c:629f:2/112 fe80::dcad:4aff:feba:cd84/64]
2024-08-31T02:21:23.270 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 magicsock: 0 active derp conns
2024-08-31T02:21:23.271 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 monitor: gateway and self IP changed: gw=172.19.12.201 self=172.19.12.202
2024-08-31T02:21:23.272 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 pm: migrating "_daemon" profile to new format
2024-08-31T02:21:23.272 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 logpolicy: using system state directory "/var/lib/tailscale"
2024-08-31T02:21:23.272 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 got LocalBackend in 22ms
2024-08-31T02:21:23.272 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:23 Start
2024-08-31T02:21:24.274 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 timeout waiting for initial portlist
2024-08-31T02:21:24.274 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 Backend: logs: be:dd217951fcc5992cad061c9dc4b9ae259804e32aaef59fb751b82af67a9a3a2f fe:
2024-08-31T02:21:24.274 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 Switching ipn state NoState -> NeedsLogin (WantRunning=false, nm=false)
2024-08-31T02:21:24.274 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 blockEngineUpdates(true)
2024-08-31T02:21:24.275 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 wgengine: Reconfig: configuring userspace WireGuard config (with 0/0 peers)
2024-08-31T02:21:24.275 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 wgengine: Reconfig: configuring router
2024-08-31T02:21:24.275 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 wgengine: Reconfig: configuring DNS
2024-08-31T02:21:24.275 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 dns: Set: {DefaultResolvers:[] Routes:{} SearchDomains:[] Hosts:0}
2024-08-31T02:21:24.275 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 dns: Resolvercfg: {Routes:{} Hosts:0 LocalDomains:[]}
2024-08-31T02:21:24.275 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 dns: OScfg: {}
2024-08-31T02:21:24.276 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 health(warnable=wantrunning-false): error: Tailscale is stopped.
2024-08-31T02:21:24.279 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 Start
2024-08-31T02:21:24.279 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 generating new machine key
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 machine key written to store
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 Backend: logs: be:dd217951fcc5992cad061c9dc4b9ae259804e32aaef59fb751b82af67a9a3a2f fe:
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 Switching ipn state NoState -> NeedsLogin (WantRunning=true, nm=false)
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 blockEngineUpdates(true)
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: client.Shutdown ...
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: mapRoutine: exiting
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 health(warnable=warming-up): error: Tailscale is starting. Please wait.
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 health(warnable=wantrunning-false): ok
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: authRoutine: exiting
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: updateRoutine: exiting
2024-08-31T02:21:24.280 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: Client.Shutdown done.
2024-08-31T02:21:24.281 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 StartLoginInteractive: url=false
2024-08-31T02:21:24.281 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: client.Login(2)
2024-08-31T02:21:24.281 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: LoginInteractive -> regen=true
2024-08-31T02:21:24.281 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: doLogin(regen=true, hasUrl=false)
2024-08-31T02:21:24.749 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: control server key from https://controlplane.tailscale.com: ts2021=[fSeS+], legacy=[nlFWp]
2024-08-31T02:21:24.749 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: Generating a new nodekey.
2024-08-31T02:21:24.751 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:24 control: RegisterReq: onode= node=[OWx3/] fup=false nks=false
2024-08-31T02:21:26.327 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 control: RegisterReq: got response; nodeKeyExpired=false, machineAuthorized=true; authURL=false
2024-08-31T02:21:26.327 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 blockEngineUpdates(false)
2024-08-31T02:21:26.484 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 control: netmap: got new dial plan from control
2024-08-31T02:21:26.484 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 using tailnet default auto-update setting: true
2024-08-31T02:21:26.484 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 EditPrefs: MaskedPrefs{AutoUpdate={Apply=true}}
2024-08-31T02:21:26.484 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 offline auto-update: starting update checks
2024-08-31T02:21:26.485 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 health(warnable=not-in-map-poll): ok
2024-08-31T02:21:26.486 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 active login:
2024-08-31T02:21:26.494 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 Switching ipn state NeedsLogin -> Starting (WantRunning=true, nm=true)
2024-08-31T02:21:26.495 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 magicsock: SetPrivateKey called (init)
2024-08-31T02:21:26.495 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 wgengine: Reconfig: configuring userspace WireGuard config (with 0/2 peers)
2024-08-31T02:21:26.495 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 wgengine: Reconfig: configuring router
2024-08-31T02:21:26.539 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 wgengine: Reconfig: configuring DNS
2024-08-31T02:21:26.539 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 dns: Set: {DefaultResolvers:[] Routes:{tailfc8bef.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[tailfc8bef.ts.net.] Hosts:3}
2024-08-31T02:21:26.539 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 dns: Resolvercfg: {Routes:{.:[fdaa::3] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:3 LocalDomains:[tailfc8bef.ts.net.]+65arpa}
2024-08-31T02:21:26.539 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 dns: OScfg: {Nameservers:[100.100.100.100] SearchDomains:[tailfc8bef.ts.net.] }
2024-08-31T02:21:26.539 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 peerapi: serving on http://100.107.93.18:44930
2024-08-31T02:21:26.539 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 peerapi: serving on http://[fd7a:115c:a1e0::3e01:5d13]:53730
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 magicsock: home is now derp-17 (lax)
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 magicsock: endpoints changed: 216.246.104.80:51337 (stun), [2605:4c40:104:6f43:0:ff0c:629f:1]:39920 (stun), 172.19.12.202:51337 (local), 172.19.12.203:51337 (local), [2605:4c40:104:6f43:0:ff0c:629f:1]:51337 (local)
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 magicsock: adding connection to derp-17 for home-keep-alive
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 magicsock: 1 active derp conns: derp-17=cr0s,wr0s
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 offline auto-update: stopping update checks
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 Switching ipn state Starting -> Running (WantRunning=true, nm=true)
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 control: NetInfo: NetInfo{varies=false hairpin= ipv6=true ipv6os=true udp=true icmpv4=false derp=#17 portmap= link="" firewallmode="ipt-default"}
2024-08-31T02:21:26.740 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 derphttp.Client.Connect: connecting to derp-17 (lax)
2024-08-31T02:21:26.741 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 health(warnable=warming-up): ok
2024-08-31T02:21:26.754 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 magicsock: derp-17 connected; connGen=1
2024-08-31T02:21:26.754 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 health(warnable=no-derp-home): ok
2024-08-31T02:21:26.755 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 health(warnable=no-derp-connection): ok
2024-08-31T02:21:26.755 app[6e825deeb00e78] lax [info] 2024/08/31 02:21:26 [RATELIMIT] format("health(warnable=%s): ok")
2024-08-31T02:21:27.141 app[6e825deeb00e78] lax [info] INFO Main child exited normally with code: 0
2024-08-31T02:21:27.155 app[6e825deeb00e78] lax [info] INFO Starting clean up.
2024-08-31T02:21:27.165 app[6e825deeb00e78] lax [info] WARN could not unmount /rootfs: EINVAL: Invalid argument
2024-08-31T02:21:27.166 app[6e825deeb00e78] lax [info] [ 4.691862] reboot: Restarting system
2024-08-31T02:21:27.439 runner[6e825deeb00e78] lax [info] machine exited with exit code 0, not restarting