Dec 06 03:02:00 volumio go-librespot[1484]: time="2025-12-06T03:02:00-05:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 10.11.12.131:50910->34.158.255.62:80: read: connection reset by peer" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=info msg="authenticated Login5" username="22*********************ri" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="completed keyexchange" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="completed challenge" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=info msg="authenticated AP" username="22*********************ri" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="re-established accesspoint connection" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=trace msg="received accesspoint ping" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 06 03:02:03 volumio go-librespot[1484]: time="2025-12-06T03:02:03-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 06 03:02:04 volumio go-librespot[1484]: time="2025-12-06T03:02:04-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 06 03:02:04 volumio go-librespot[1484]: time="2025-12-06T03:02:04-05:00" level=trace msg="received accesspoint pong ack" Dec 06 03:02:08 volumio go-librespot[1484]: time="2025-12-06T03:02:08-05:00" level=error msg="did not receive last pong from dealer, 120s passed" Dec 06 03:02:09 volumio go-librespot[1484]: time="2025-12-06T03:02:09-05:00" level=debug msg="dealer connection opened" Dec 06 03:02:09 volumio go-librespot[1484]: time="2025-12-06T03:02:09-05:00" level=debug msg="re-established dealer connection" Dec 06 03:02:09 volumio go-librespot[1484]: time="2025-12-06T03:02:09-05:00" level=debug msg="received connection id: MmJiZWQ0OTktZTdm...MzgyNjI5Q0RFNQ==" Dec 06 03:02:09 volumio go-librespot[1484]: time="2025-12-06T03:02:09-05:00" level=debug msg="put connect state because NEW_DEVICE" Dec 06 03:02:38 volumio go-librespot[1484]: time="2025-12-06T03:02:38-05:00" level=trace msg="sent dealer ping" Dec 06 03:02:38 volumio go-librespot[1484]: time="2025-12-06T03:02:38-05:00" level=trace msg="received dealer pong" Dec 06 03:02:41 volumio bash[32490]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 06 03:02:41 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 06 03:02:41 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 06 03:03:08 volumio go-librespot[1484]: time="2025-12-06T03:03:08-05:00" level=trace msg="sent dealer ping" Dec 06 03:03:08 volumio go-librespot[1484]: time="2025-12-06T03:03:08-05:00" level=trace msg="received dealer pong" Dec 06 03:03:38 volumio go-librespot[1484]: time="2025-12-06T03:03:38-05:00" level=trace msg="sent dealer ping" Dec 06 03:03:38 volumio go-librespot[1484]: time="2025-12-06T03:03:38-05:00" level=trace msg="received dealer pong" Dec 06 03:03:46 volumio dhcpcd[785]: eth0: NAK: from 10.11.12.13 Dec 06 03:03:46 volumio dhcpcd[691]: eth0: pid 785 deleted IP address 10.11.12.131/24 Dec 06 03:03:46 volumio dhcpcd[691]: eth0: deleting route to 10.11.12.0/24 Dec 06 03:03:46 volumio avahi-daemon[707]: Withdrawing address record for 10.11.12.131 on eth0. Dec 06 03:03:46 volumio avahi-daemon[707]: Leaving mDNS multicast group on interface eth0.IPv4 with address 10.11.12.131. Dec 06 03:03:46 volumio avahi-daemon[707]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 06 03:03:46 volumio dhcpcd[691]: eth0: deleting default route via 10.11.12.13 Dec 06 03:03:46 volumio dhcpcd[785]: eth0: deleting route to 10.11.12.0/24 Dec 06 03:03:46 volumio dhcpcd[785]: eth0: deleting default route via 10.11.12.13 Dec 06 03:03:46 volumio volumio[1066]: info: Discovery: A device disappeared from network Dec 06 03:03:46 volumio volumio[1066]: info: Discovery: Device volumio disappeared from network Dec 06 03:03:46 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 06 03:03:46 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 06 03:03:46 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 06 03:03:46 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 06 03:03:46 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 06 03:03:46 volumio dhcpcd[785]: eth0: soliciting a DHCP lease Dec 06 03:03:46 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 06 03:03:46 volumio welcome[403]: Resolved ip:[0] Dec 06 03:03:46 volumio dhcpcd[691]: eth0: soliciting a DHCP lease Dec 06 03:03:46 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 06 03:03:46 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 06 03:03:46 volumio dhcpcd[691]: eth0: offered 10.11.12.131 from 10.11.12.13 Dec 06 03:03:46 volumio dhcpcd[691]: eth0: probing address 10.11.12.131/24 Dec 06 03:03:48 volumio ntpd[984]: IO: Deleting interface #4 eth0, 10.11.12.131#123, interface stats: received=554, sent=568, dropped=0, active_time=85504 secs Dec 06 03:03:48 volumio ntpd[984]: PROTO: 67.217.246.204 unlink local addr 10.11.12.131 -> Dec 06 03:03:48 volumio ntpd[984]: PROTO: 44.190.5.123 unlink local addr 10.11.12.131 -> Dec 06 03:03:48 volumio ntpd[984]: PROTO: 96.60.247.29 unlink local addr 10.11.12.131 -> Dec 06 03:03:48 volumio ntpd[984]: PROTO: 45.63.54.13 unlink local addr 10.11.12.131 -> Dec 06 03:03:48 volumio ntpd[984]: PROTO: 141.11.228.173 unlink local addr 10.11.12.131 -> Dec 06 03:03:48 volumio ntpd[984]: PROTO: 23.150.40.242 unlink local addr 10.11.12.131 -> Dec 06 03:03:48 volumio ntpd[984]: PROTO: 96.231.54.40 unlink local addr 10.11.12.131 -> Dec 06 03:03:50 volumio dhcpcd[785]: eth0: offered 10.11.12.131 from 10.11.12.13 Dec 06 03:03:50 volumio dhcpcd[785]: eth0: probing address 10.11.12.131/24 Dec 06 03:03:51 volumio dhcpcd[691]: eth0: leased 10.11.12.131 for 7200 seconds Dec 06 03:03:51 volumio avahi-daemon[707]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.11.12.131. Dec 06 03:03:51 volumio avahi-daemon[707]: New relevant interface eth0.IPv4 for mDNS. Dec 06 03:03:51 volumio dhcpcd[691]: eth0: adding route to 10.11.12.0/24 Dec 06 03:03:51 volumio avahi-daemon[707]: Registering new address record for 10.11.12.131 on eth0.IPv4. Dec 06 03:03:51 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 06 03:03:51 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 06 03:03:51 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 06 03:03:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 06 03:03:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 06 03:03:51 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 06 03:03:51 volumio welcome[436]: Resolved ip:[1] 10.11.12.131 Dec 06 03:03:51 volumio dhcpcd[691]: eth0: adding default route via 10.11.12.13 Dec 06 03:03:51 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 06 03:03:51 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 06 03:03:51 volumio volumio[1066]: compat.c: read() failed: Resource temporarily unavailable Dec 06 03:03:51 volumio volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 03:03:51 volumio volumio[1066]: Error: dns service error: unknown Dec 06 03:03:51 volumio volumio[1066]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 06 03:03:51 volumio volumio[1066]: errorCode: -65537 Dec 06 03:03:51 volumio volumio[1066]: } Dec 06 03:03:51 volumio volumio[1066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 03:03:52 volumio sudo[477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 03:02' Dec 06 03:03:52 volumio sudo[477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"