Dec 13 15:36:50 national-engineers systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 13 15:36:50 national-engineers systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 13 15:36:50 national-engineers systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 13 15:36:50 national-engineers tailscaled[692]: control: control server key from https://controlplane.tailscale.com: ts2021=[fSeS+], legacy=[nlFWp] Dec 13 15:36:50 national-engineers tailscaled[692]: control: RegisterReq: onode= node=[42hFI] fup=false nks=false Dec 13 15:36:50 national-engineers systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 13 15:36:50 national-engineers systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 13 15:36:50 national-engineers systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 13 15:36:50 national-engineers systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 13 15:36:50 national-engineers tailscaled[692]: control: RegisterReq: got response; nodeKeyExpired=false, machineAuthorized=true; authURL=false Dec 13 15:36:50 national-engineers tailscaled[692]: health(warnable=login-state): ok Dec 13 15:36:50 national-engineers volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 13 15:36:50 national-engineers volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 13 15:36:50 national-engineers volumio[1187]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 13 15:36:50 national-engineers tailscaled[692]: control: netmap: got new dial plan from control Dec 13 15:36:50 national-engineers tailscaled[692]: health(warnable=not-in-map-poll): ok Dec 13 15:36:50 national-engineers tailscaled[692]: active login: sadriwalamohammed03@gmail.com Dec 13 15:36:50 national-engineers tailscaled[692]: offline auto-update: stopping update checks Dec 13 15:36:50 national-engineers tailscaled[692]: Switching ipn state NoState -> Starting (WantRunning=true, nm=true) Dec 13 15:36:50 national-engineers tailscaled[692]: magicsock: SetPrivateKey called (init) Dec 13 15:36:50 national-engineers tailscaled[692]: wgengine: Reconfig: configuring userspace WireGuard config (with 0/0 peers) Dec 13 15:36:50 national-engineers tailscaled[692]: wgengine: Reconfig: configuring router Dec 13 15:36:50 national-engineers tailscaled[692]: wgengine: Reconfig: user dialer Dec 13 15:36:50 national-engineers tailscaled[692]: tsdial: bart table size: 2 Dec 13 15:36:50 national-engineers tailscaled[692]: wgengine: Reconfig: configuring DNS Dec 13 15:36:50 national-engineers tailscaled[692]: dns: Set: {DefaultResolvers:[] Routes:{tailf18a08.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[tailf18a08.ts.net.] Hosts:1} Dec 13 15:36:50 national-engineers tailscaled[692]: dns: Resolvercfg: {Routes:{.:[8.8.8.8 4.2.2.2 208.67.222.222 208.67.220.220] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:1 LocalDomains:[tailf18a08.ts.net.]+65arpa} Dec 13 15:36:50 national-engineers tailscaled[692]: dns: OScfg: {Nameservers:[100.100.100.100] SearchDomains:[tailf18a08.ts.net.] } Dec 13 15:36:50 national-engineers systemd[1]: Reached target ip-changed@tailscale0.target - IP Address changed on tailscale0. Dec 13 15:36:51 national-engineers tailscaled[692]: peerapi: serving on http://100.123.105.5:64194 Dec 13 15:36:51 national-engineers tailscaled[692]: peerapi: failed to do peerAPI listen, harmless (netstack available) but error was: listen tcp6 [fd7a:115c:a1e0::a901:6925]:0: bind: cannot assign requested address Dec 13 15:36:51 national-engineers tailscaled[692]: peerapi: serving on http://[fd7a:115c:a1e0::a901:6925]:1 Dec 13 15:36:51 national-engineers volumio[1187]: info: Discovery: A device disappeared from network Dec 13 15:36:51 national-engineers volumio[1187]: info: Discovery: A device disappeared from network Dec 13 15:36:51 national-engineers volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 13 15:36:51 national-engineers volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 13 15:36:51 national-engineers volumio[1187]: info: Discovery: adding a0b4a0e1-ad2a-4359-9929-956516a5aa85 Dec 13 15:36:51 national-engineers volumio[1187]: info: Discovery: Found device National Engineers Dec 13 15:36:51 national-engineers volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 13 15:36:51 national-engineers volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 13 15:36:51 national-engineers volumio[1187]: info: Discovery: this is already registered, a0b4a0e1-ad2a-4359-9929-956516a5aa85 Dec 13 15:36:51 national-engineers volumio[1187]: info: Discovery: Found device National Engineers Dec 13 15:36:51 national-engineers volumio[1187]: info: CoreCommandRouter::volumioGetState Dec 13 15:36:51 national-engineers volumio[1187]: info: CorePlayQueue::getTrack 0 Dec 13 15:36:51 national-engineers tailscaled[692]: LinkChange: major, rebinding. New state: interfaces.State{defaultRoute=eth0 ifs={eth0:[192.168.1.3/24] tailscale0:[100.123.105.5/32]} v4=true v6=false} Dec 13 15:36:51 national-engineers tailscaled[692]: dns: Set: {DefaultResolvers:[] Routes:{tailf18a08.ts.net.:[] ts.net.:[199.247.155.53 2620:111:8007::53]}+65arpa SearchDomains:[tailf18a08.ts.net.] Hosts:1} Dec 13 15:36:51 national-engineers tailscaled[692]: dns: Resolvercfg: {Routes:{.:[8.8.8.8 4.2.2.2 208.67.222.222 208.67.220.220] ts.net.:[199.247.155.53 2620:111:8007::53]} Hosts:1 LocalDomains:[tailf18a08.ts.net.]+65arpa} Dec 13 15:36:51 national-engineers tailscaled[692]: dns: OScfg: {Nameservers:[100.100.100.100] SearchDomains:[tailf18a08.ts.net.] } Dec 13 15:36:51 national-engineers tailscaled[692]: wgengine: set DNS config again after major link change Dec 13 15:36:51 national-engineers tailscaled[692]: router: portUpdate(port=41641, network=udp6) Dec 13 15:36:51 national-engineers tailscaled[692]: router: portUpdate(port=41641, network=udp4) Dec 13 15:36:51 national-engineers tailscaled[692]: Rebind; defIf="eth0", ips=[192.168.1.3/24] Dec 13 15:36:51 national-engineers tailscaled[692]: magicsock: 0 active derp conns Dec 13 15:36:52 national-engineers ntpd[991]: IO: Listen normally on 4 tailscale0 100.123.105.5:123 Dec 13 15:36:52 national-engineers ntpd[991]: IO: new interface(s) found: waking up resolver Dec 13 15:36:52 national-engineers ntpd[991]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 13 15:36:52 national-engineers ntpd[991]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 13 15:36:52 national-engineers ntpd[991]: DNS: Pool taking: 13.200.20.166 Dec 13 15:36:52 national-engineers ntpd[991]: DNS: Pool taking: 172.236.180.15 Dec 13 15:36:52 national-engineers ntpd[991]: DNS: Pool taking: 14.139.60.103 Dec 13 15:36:52 national-engineers ntpd[991]: DNS: Pool taking: 95.216.144.226 Dec 13 15:36:52 national-engineers ntpd[991]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 13 15:36:52 national-engineers go-librespot[1642]: time="2025-12-13T15:36:52+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 13 15:36:52 national-engineers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 13 15:36:52 national-engineers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 13 15:36:52 national-engineers volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 15:36:52 national-engineers volumio[1187]: Error: socket hang up Dec 13 15:36:52 national-engineers volumio[1187]: at connResetException (node:internal/errors:720:14) Dec 13 15:36:52 national-engineers volumio[1187]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 13 15:36:52 national-engineers volumio[1187]: at Socket.emit (node:events:526:35) Dec 13 15:36:52 national-engineers volumio[1187]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 13 15:36:52 national-engineers volumio[1187]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 13 15:36:52 national-engineers volumio[1187]: code: 'ECONNRESET', Dec 13 15:36:52 national-engineers volumio[1187]: response: undefined Dec 13 15:36:52 national-engineers volumio[1187]: } Dec 13 15:36:52 national-engineers volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 15:36:53 national-engineers ntpd[991]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 17.253.18.99 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 95.216.192.15 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 103.136.36.100 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 162.159.200.123 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 2605:e440:19::1e7 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 2605:e440:19::342 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 2a01:4f9:c010:c22::1 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: Pool taking: 2402:1f00:8300:821::123 Dec 13 15:36:53 national-engineers ntpd[991]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: Pool taking: 192.46.211.253 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: Pool taking: 217.217.249.232 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: Pool taking: 162.159.200.1 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: Pool taking: 103.82.208.166 Dec 13 15:36:54 national-engineers ntpd[991]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 13 15:36:54 national-engineers tailscaled[692]: netcheck: DetectCaptivePortal(found=false) Dec 13 15:36:54 national-engineers tailscaled[692]: magicsock: home DERP changing from derp-0 [0ms] to derp-6 [37ms] Dec 13 15:36:54 national-engineers tailscaled[692]: magicsock: home is now derp-6 (blr) Dec 13 15:36:54 national-engineers tailscaled[692]: health(warnable=no-derp-home): ok Dec 13 15:36:54 national-engineers tailscaled[692]: control: NetInfo: NetInfo{varies=false ipv6=false ipv6os=false udp=true icmpv4=false derp=#6 portmap= link="" firewallmode="ipt-default"} Dec 13 15:36:54 national-engineers tailscaled[692]: magicsock: endpoints changed: 116.72.58.138:41641 (stun), 192.168.1.3:41641 (local) Dec 13 15:36:54 national-engineers tailscaled[692]: magicsock: adding connection to derp-6 for home-keep-alive Dec 13 15:36:54 national-engineers tailscaled[692]: magicsock: 1 active derp conns: derp-6=cr0s,wr0s Dec 13 15:36:54 national-engineers tailscaled[692]: derphttp.Client.Connect: connecting to derp-6 (blr) Dec 13 15:36:54 national-engineers tailscaled[692]: Switching ipn state Starting -> Running (WantRunning=true, nm=true) Dec 13 15:36:54 national-engineers tailscaled[692]: magicsock: derp-6 connected; connGen=1 Dec 13 15:36:54 national-engineers tailscaled[692]: health(warnable=no-derp-connection): ok Dec 13 15:36:54 national-engineers nmbd[1084]: [2025/12/13 15:36:54.992146, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Dec 13 15:36:54 national-engineers nmbd[1084]: register_name_response: Answer name <00> differs from question name <00>. Dec 13 15:36:55 national-engineers nmbd[1084]: [2025/12/13 15:36:55.000155, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Dec 13 15:36:55 national-engineers nmbd[1084]: register_name_response: Answer name <03> differs from question name <03>. Dec 13 15:36:55 national-engineers nmbd[1084]: [2025/12/13 15:36:55.000402, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Dec 13 15:36:55 national-engineers nmbd[1084]: register_name_response: Answer name <20> differs from question name <20>. Dec 13 15:36:55 national-engineers ntpd[991]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 13 15:36:55 national-engineers ntpd[991]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 13 15:36:55 national-engineers ntpd[991]: DNS: Pool skipping: 103.136.36.100 Dec 13 15:36:55 national-engineers ntpd[991]: DNS: Pool skipping: 162.159.200.1 Dec 13 15:36:55 national-engineers ntpd[991]: DNS: Pool skipping: 17.253.18.99 Dec 13 15:36:55 national-engineers ntpd[991]: DNS: Pool taking: 86.107.100.245 Dec 13 15:36:55 national-engineers ntpd[991]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 13 15:36:55 national-engineers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 13 15:36:55 national-engineers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 13 15:36:55 national-engineers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 13 15:36:55 national-engineers go-librespot[1836]: go-librespot daemon starting... Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=info msg="running go-librespot 0.4.0" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=debug msg="app state loaded" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=debug msg="stored credentials not found" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 13 15:36:55 national-engineers go-librespot[1837]: time="2025-12-13T15:36:55+05:30" level=info msg="zeroconf server listening on port 39909" Dec 13 15:36:55 national-engineers sudo[1847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 15:35' Dec 13 15:36:55 national-engineers sudo[1847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:36:55 national-engineers tailscaled[692]: logtail: upload succeeded after 1 failures and 50s PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"