Apr 12 21:28:02 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") (8 dropped) Apr 12 21:28:02 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp6.tailscale.com", "68.183.90.120") for "controlplane.tailscale.com" error: Get "https://derp6.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": context deadline exceeded Apr 12 21:28:02 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp3f.tailscale.com", "2600:3c15::2000:53ff:fe48:a668") for "controlplane.tailscale.com" ... Apr 12 21:28:02 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") Apr 12 21:28:02 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp3f.tailscale.com", "2600:3c15::2000:53ff:fe48:a668") for "controlplane.tailscale.com" error: Get "https://derp3f.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2600:3c15::2000:53ff:fe48:a668]:443: connect: cannot assign requested address Apr 12 21:28:05 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp22c.tailscale.com", "45.159.98.253") for "controlplane.tailscale.com" error: Get "https://derp22c.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": context deadline exceeded Apr 12 21:28:05 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp8d.tailscale.com", "2a03:b0c0:1:d0::e08:e001") for "controlplane.tailscale.com" error: Get "https://derp8d.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2a03:b0c0:1:d0::e08:e001]:443: connect: cannot assign requested address Apr 12 21:28:05 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") Apr 12 21:28:08 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") (3 dropped) Apr 12 21:28:08 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp1g.tailscale.com", "2607:f740:f::3eb") for "controlplane.tailscale.com" ... Apr 12 21:28:08 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp17c.tailscale.com", "208.111.40.12") for "controlplane.tailscale.com" ... Apr 12 21:28:08 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") Apr 12 21:28:14 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") (4 dropped) Apr 12 21:28:14 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp3e.tailscale.com", "172.237.72.43") for "controlplane.tailscale.com" error: Get "https://derp3e.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": context deadline exceeded Apr 12 21:28:14 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp25b.tailscale.com", "2c0f:edb0:2000:1::2e9") for "controlplane.tailscale.com" error: Get "https://derp25b.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2c0f:edb0:2000:1::2e9]:443: connect: cannot assign requested address Apr 12 21:28:14 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") Apr 12 21:28:16 volumio-fg5 tailscaled[1008]: Received error: fetch control key: Get "https://controlplane.tailscale.com/key?v=133": failed to resolve "controlplane.tailscale.com": no DNS fallback candidates remain for "controlplane.tailscale.com" Apr 12 21:28:16 volumio-fg5 tailscaled[1008]: control: doLogin(regen=false, hasUrl=false) Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") (5 dropped) Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp18d.tailscale.com", "176.58.90.104") for "controlplane.tailscale.com" ... Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") (2 dropped) Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp18d.tailscale.com", "176.58.90.104") for "controlplane.tailscale.com" error: Get "https://derp18d.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp 176.58.90.104:443: connect: no route to host Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp2f.tailscale.com", "2607:f740:0:3f::f4") for "controlplane.tailscale.com" ... Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp2f.tailscale.com", "2607:f740:0:3f::f4") for "controlplane.tailscale.com" error: Get "https://derp2f.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2607:f740:0:3f::f4]:443: connect: cannot assign requested address Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp20b.tailscale.com", "103.6.84.152") for "controlplane.tailscale.com" ... Apr 12 21:28:26 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") Apr 12 21:28:27 volumio-fg5 tailscaled[1008]: Received error: fetch control key: Get "https://controlplane.tailscale.com/key?v=133": failed to resolve "controlplane.tailscale.com": no DNS fallback candidates remain for "controlplane.tailscale.com" Apr 12 21:28:28 volumio-fg5 tailscaled[1008]: control: doLogin(regen=false, hasUrl=false) Apr 12 21:28:38 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") (9 dropped) Apr 12 21:28:38 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp19d.tailscale.com", "45.159.97.233") for "controlplane.tailscale.com" ... Apr 12 21:28:39 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") (10 dropped) Apr 12 21:28:39 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp19d.tailscale.com", "45.159.97.233") for "controlplane.tailscale.com" error: Get "https://derp19d.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp 45.159.97.233:443: connect: no route to host Apr 12 21:28:39 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp13d.tailscale.com", "2607:f740:16::c23") for "controlplane.tailscale.com" ... Apr 12 21:28:39 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp13d.tailscale.com", "2607:f740:16::c23") for "controlplane.tailscale.com" error: Get "https://derp13d.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2607:f740:16::c23]:443: connect: cannot assign requested address Apr 12 21:28:39 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp9f.tailscale.com", "209.177.156.197") for "controlplane.tailscale.com" ... Apr 12 21:28:39 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") Apr 12 21:28:40 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp9f.tailscale.com", "209.177.156.197") for "controlplane.tailscale.com" error: Get "https://derp9f.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp 209.177.156.197:443: connect: no route to host Apr 12 21:28:40 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") Apr 12 21:28:42 volumio-fg5 tailscaled[1008]: Received error: fetch control key: Get "https://controlplane.tailscale.com/key?v=133": failed to resolve "controlplane.tailscale.com": no DNS fallback candidates remain for "controlplane.tailscale.com" Apr 12 21:28:43 volumio-fg5 tailscaled[1008]: control: doLogin(regen=false, hasUrl=false) Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") (9 dropped) Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp11f.tailscale.com", "172.237.61.197") for "controlplane.tailscale.com" ... Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") (9 dropped) Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp11f.tailscale.com", "172.237.61.197") for "controlplane.tailscale.com" error: Get "https://derp11f.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp 172.237.61.197:443: connect: no route to host Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp26c.tailscale.com", "2a01:4f8:1c1c:5c70::1") for "controlplane.tailscale.com" ... Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp26c.tailscale.com", "2a01:4f8:1c1c:5c70::1") for "controlplane.tailscale.com" error: Get "https://derp26c.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2a01:4f8:1c1c:5c70::1]:443: connect: cannot assign requested address Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp25c.tailscale.com", "102.67.167.37") for "controlplane.tailscale.com" ... Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp25c.tailscale.com", "102.67.167.37") for "controlplane.tailscale.com" error: Get "https://derp25c.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp 102.67.167.37:443: connect: no route to host Apr 12 21:28:53 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") Apr 12 21:28:56 volumio-fg5 tailscaled[1008]: Received error: fetch control key: Get "https://controlplane.tailscale.com/key?v=133": failed to resolve "controlplane.tailscale.com": no DNS fallback candidates remain for "controlplane.tailscale.com" Apr 12 21:28:56 volumio-fg5 tailscaled[1008]: control: doLogin(regen=false, hasUrl=false) Apr 12 21:29:06 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") (9 dropped) Apr 12 21:29:06 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp27d.tailscale.com", "178.156.152.106") for "controlplane.tailscale.com" ... Apr 12 21:29:09 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") (9 dropped) Apr 12 21:29:09 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp27d.tailscale.com", "178.156.152.106") for "controlplane.tailscale.com" error: Get "https://derp27d.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": context deadline exceeded Apr 12 21:29:09 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp7.tailscale.com", "2401:c080:1000:467f:5400:2ff:feee:22aa") for "controlplane.tailscale.com" ... Apr 12 21:29:09 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp7.tailscale.com", "2401:c080:1000:467f:5400:2ff:feee:22aa") for "controlplane.tailscale.com" error: Get "https://derp7.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2401:c080:1000:467f:5400:2ff:feee:22aa]:443: connect: cannot assign requested address Apr 12 21:29:09 volumio-fg5 tailscaled[1008]: control: trying bootstrapDNS("derp19d.tailscale.com", "45.159.97.233") for "controlplane.tailscale.com" ... Apr 12 21:29:09 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: trying bootstrapDNS(%q, %q) for %q ...") Apr 12 21:29:12 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp19d.tailscale.com", "45.159.97.233") for "controlplane.tailscale.com" error: Get "https://derp19d.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": context deadline exceeded Apr 12 21:29:12 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp1c.tailscale.com", "2604:a880:800:10::7a0:e001") for "controlplane.tailscale.com" error: Get "https://derp1c.tailscale.com/bootstrap-dns?q=controlplane.tailscale.com": dial tcp [2604:a880:800:10::7a0:e001]:443: connect: cannot assign requested address Apr 12 21:29:12 volumio-fg5 tailscaled[1008]: [RATELIMIT] format("control: bootstrapDNS(%q, %q) for %q error: %v") Apr 12 21:29:14 volumio-fg5 tailscaled[1008]: control: bootstrapDNS("derp26b.tailscale.com", "167.235.72.200") for "controlplane.tailscale.com" = [2606:b740:49::111 192.200.0.112 2606:b740:49::101 192.200.0.114 2606:b740:49::113 192.200.0.110 192.200.0.109 192.200.0.103 192.200.0.105 2606:b740:49::106 2606:b740:49::107 192.200.0.111 2606:b740:49::108 2606:b740:49::110 2606:b740:49::104 192.200.0.116 192.200.0.104 192.200.0.113 2606:b740:49::116 2606:b740:49::109 192.200.0.115 2606:b740:49::115 192.200.0.106 2606:b740:49::105 2606:b740:49::103 2606:b740:49::102 2606:b740:49::112 192.200.0.108 2606:b740:49::114 192.200.0.102 192.200.0.107 192.200.0.101] Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: control: control server key from https://controlplane.tailscale.com: ts2021=[fSeS+], legacy=[nlFWp] Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: control: RegisterReq: onode= node=[qyzqd] fup=false nks=false Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: control: RegisterReq: got response; nodeKeyExpired=false, machineAuthorized=true; authURL=false Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: health(warnable=login-state): ok Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: health(warnable=not-in-map-poll): ok Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: control: netmap: got new dial plan from control Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: active login: fulvio.gianotti@gmail.com Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: netmap: suggested exit node: no preferred DERP, try again later Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: offline auto-update: stopping update checks Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: Switching ipn state NoState -> Starting (WantRunning=true, nm=true) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: SetPrivateKey called (init) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: wgengine: Reconfig: configuring userspace WireGuard config (with 0/21 peers) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: wgengine: Reconfig: configuring router Apr 12 21:29:15 volumio-fg5 systemd[1]: Reached target ip-changed@tailscale0.target - IP Address changed on tailscale0. Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: router: enabling connmark-based rp_filter workaround Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: wgengine: Reconfig: user dialer Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: tsdial: bart table size: 24 Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: wgengine: Reconfig: configuring DNS Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: dns: Set: {DefaultResolvers:[] Routes:{tail337948.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[tail337948.ts.net.] Hosts:22} Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: dns: Resolvercfg: {Routes:{.:[192.168.68.1 208.67.222.222 208.67.220.220] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:22 LocalDomains:[tail337948.ts.net.]+65arpa} Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: dns: OScfg: {Nameservers:[100.100.100.100 fd7a:115c:a1e0::53] SearchDomains:[tail337948.ts.net.] } Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: peerapi: serving on http://100.65.50.78:34307 Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: peerapi: failed to do peerAPI listen, harmless (netstack available) but error was: listen tcp6 [fd7a:115c:a1e0::9901:3294]:0: bind: cannot assign requested address Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: peerapi: serving on http://[fd7a:115c:a1e0::9901:3294]:1 Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: portmapper: UPnP meta changed: [{Location:http://192.168.68.1:1900/igd.xml Server:TPOS/V1.0.0 UPnP/1.0 S7/1.0 USN:uuid:ba6a6b28-1dda-11b2-9ea9-F5CB22DB8C6D::urn:schemas-upnp-org:device:InternetGatewayDevice:1}] Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: home DERP changing from derp-0 [0ms] to derp-26 [31ms] Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: home is now derp-26 (nue) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: health(warnable=no-derp-home): ok Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: adding connection to derp-26 for home-keep-alive Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: 1 active derp conns: derp-26=cr0s,wr0s Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: derphttp.Client.Connect: connecting to derp-26 (nue) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: endpoints changed: 87.10.61.96:61662 (stun), 192.168.68.119:41641 (local) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: control: NetInfo: NetInfo{varies=false ipv6=false ipv6os=false udp=true icmpv4=false derp=#26 portmap=U link="" firewallmode="ipt-default"} Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: Switching ipn state Starting -> Running (WantRunning=true, nm=true) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: portmapper: saw UPnP type WANIPConnection1 at http://192.168.68.1:1900/igd.xml; S7 (TP-Link), method=single Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: derp-26 connected; connGen=1 Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: health(warnable=no-derp-connection): ok Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: control: NetInfo: NetInfo{varies=false ipv6=false ipv6os=false udp=true icmpv4=false derp=#26 portmap=active-U link="" firewallmode="ipt-default"} Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: magicsock: endpoints changed: 192.168.1.13:61250 (portmap), 87.10.61.96:61662 (stun), 192.168.68.119:41641 (local) Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: netmap: suggested exit node: () Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: wgengine: Reconfig: [dV0/b] changed from "discokey:97299d37c8779066a6f00c6dc9aec928cbd202b7388d42c879bb64935a60163f" to "discokey:e6ab9c8b72a8d4c29cbbbe6ef2369301fce5397c0f3133d9ff7d96e1d19b456f" Apr 12 21:29:15 volumio-fg5 tailscaled[1008]: netmap: suggested exit node: () Apr 12 21:29:16 volumio-fg5 ntpd[1153]: IO: Listen normally on 5 tailscale0 100.65.50.78:123 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: IO: new interface(s) found: waking up resolver Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: Pool taking: 85.199.214.99 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: Pool taking: 172.232.208.229 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: Pool taking: 129.152.16.145 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: Pool taking: 93.44.243.146 Apr 12 21:29:16 volumio-fg5 ntpd[1153]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 31.14.133.122 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 162.159.200.1 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 172.232.209.103 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 37.247.53.178 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 2a00:6d41:200:2::14 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 2a00:6d41:200:2::15 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 2603:c020:8017:3e00::123:123 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: Pool taking: 2a00:6d41:10:1194::3 Apr 12 21:29:17 volumio-fg5 ntpd[1153]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: Pool taking: 185.157.229.254 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: Pool taking: 185.208.47.14 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: Pool taking: 204.216.214.76 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: Pool skipping: 162.159.200.1 Apr 12 21:29:18 volumio-fg5 ntpd[1153]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: Pool taking: 81.56.230.156 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: Pool skipping: 204.216.214.76 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: Pool taking: 151.22.209.90 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: Pool taking: 95.110.254.234 Apr 12 21:29:19 volumio-fg5 ntpd[1153]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin bluetooth to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin multiroom to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin metavolumio to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin cd_controller to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: Starting MyVolumio Remote Streaming Endpoints Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: MyVolumio login type: Token Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 12 21:29:19 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 12 21:29:20 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 12 21:29:20 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 12 21:29:20 volumio-fg5 volumio[1440]: info: Streaming services startup Apr 12 21:29:20 volumio-fg5 volumio[1440]: info: Starting Streaming Daemon Apr 12 21:29:20 volumio-fg5 sudo[3131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 21:29:20 volumio-fg5 sudo[3131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 21:29:20 volumio-fg5 volumio[1440]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 12 21:29:20 volumio-fg5 sudo[3131]: pam_unix(sudo:session): session closed for user root Apr 12 21:29:20 volumio-fg5 volumio[1440]: error: Cannot start Volumio Streaming Daemon Apr 12 21:29:20 volumio-fg5 volumio[1440]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 21:29:20 volumio-fg5 volumio[1440]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 21:29:20 volumio-fg5 volumio[1440]: error: MyVolumio Custom Token format not valid, refreshing it Apr 12 21:29:21 volumio-fg5 volumio[1440]: info: MyVolumio login type: Token Apr 12 21:29:21 volumio-fg5 volumio[1440]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 12 21:29:21 volumio-fg5 volumio[1440]: info: MyVolumio token set successfully Apr 12 21:29:21 volumio-fg5 volumio[1440]: info: MYVOLUMIO: Adding device Apr 12 21:29:21 volumio-fg5 volumio[1440]: info: MYVOLUMIO: Evaluating Server Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: MyVolumio status changed Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: Streaming services startup Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: Starting Streaming Daemon Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: Removing browser output: myVolumio user plan is not superstar Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: Removing audio output: Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: Stoppping Tunnel 1 Apr 12 21:29:22 volumio-fg5 sudo[3174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 21:29:22 volumio-fg5 sudo[3174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 21:29:22 volumio-fg5 sudo[3176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 12 21:29:22 volumio-fg5 sudo[3176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 sudo[3174]: pam_unix(sudo:session): session closed for user root Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 volumio[1440]: error: Cannot start Volumio Streaming Daemon Apr 12 21:29:22 volumio-fg5 volumio[1440]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 21:29:22 volumio-fg5 volumio[1440]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 12 21:29:22 volumio-fg5 sudo[3176]: pam_unix(sudo:session): session closed for user root Apr 12 21:29:22 volumio-fg5 volumio[1440]: info: Remote SSH Stopped Apr 12 21:29:22 volumio-fg5 ntpd[1153]: CLOCK: time stepped by -0.136212 Apr 12 21:29:22 volumio-fg5 systemd-journald[574]: Time jumped backwards, rotating. Apr 12 21:29:22 volumio-fg5 ntpd[1153]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Apr 12 21:29:22 volumio-fg5 volumio[1440]: error: Failed to ping endpoint us4.myvolumio.org : unknown error Apr 12 21:29:22 volumio-fg5 volumio[1440]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 21:29:22 volumio-fg5 volumio[1440]: Error: Unable to resolve or reject the same promise twice Apr 12 21:29:22 volumio-fg5 volumio[1440]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 12 21:29:22 volumio-fg5 volumio[1440]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Apr 12 21:29:22 volumio-fg5 volumio[1440]: at Socket.emit (node:events:514:28) Apr 12 21:29:22 volumio-fg5 volumio[1440]: at addChunk (node:internal/streams/readable:343:12) Apr 12 21:29:22 volumio-fg5 volumio[1440]: at readableAddChunk (node:internal/streams/readable:316:9) Apr 12 21:29:22 volumio-fg5 volumio[1440]: at Readable.push (node:internal/streams/readable:253:10) Apr 12 21:29:22 volumio-fg5 volumio[1440]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 12 21:29:22 volumio-fg5 volumio[1440]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 21:29:22 volumio-fg5 sudo[3194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 21:28' Apr 12 21:29:22 volumio-fg5 sudo[3194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"