Jun 05 19:30:07 volumio dhcpcd[662]: eth0: deleting route to 192.168.1.0/24 Jun 05 19:30:07 volumio dhcpcd[662]: eth0: deleting default route via 192.168.1.1 Jun 05 19:30:07 volumio dhcpcd[524]: eth0: pid 662 deleted route to 192.168.1.0/24 Jun 05 19:30:07 volumio dhcpcd[524]: eth0: pid 662 deleted default route via 192.168.1.1 Jun 05 19:30:07 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 05 19:30:07 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 05 19:30:07 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 05 19:30:07 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 05 19:30:07 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 05 19:30:07 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 05 19:30:07 volumio welcome[13452]: Resolved ip:[2] 192.168.1.8 192.168.1.9 Jun 05 19:30:07 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 05 19:30:07 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 05 19:30:18 volumio go-librespot[2967]: time="2025-06-05T19:30:18+07:00" level=trace msg="sent dealer ping" Jun 05 19:30:18 volumio go-librespot[2967]: time="2025-06-05T19:30:18+07:00" level=trace msg="received dealer pong" Jun 05 19:30:48 volumio go-librespot[2967]: time="2025-06-05T19:30:48+07:00" level=trace msg="sent dealer ping" Jun 05 19:31:18 volumio go-librespot[2967]: time="2025-06-05T19:31:18+07:00" level=error msg="did not receive last pong from dealer, 60s passed" Jun 05 19:31:18 volumio go-librespot[2967]: time="2025-06-05T19:31:18+07:00" level=error msg="did not receive last pong ack from accesspoint, 120s passed" Jun 05 19:31:18 volumio go-librespot[2967]: time="2025-06-05T19:31:18+07:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.8:57478->104.199.241.202:4070: use of closed network connection" Jun 05 19:31:18 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 05 19:31:18 volumio dbus-daemon[539]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.193' (uid=0 pid=13564 comm="timedatectl show --property=NTPSynchronized --valu") Jun 05 19:31:18 volumio go-librespot[2967]: time="2025-06-05T19:31:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Jun 05 19:31:18 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 05 19:31:18 volumio dbus-daemon[539]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 05 19:31:18 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 05 19:31:18 volumio setdatetime-helper.sh[13563]: Time is already synchronized. Jun 05 19:31:18 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 05 19:31:18 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 05 19:31:23 volumio go-librespot[2967]: time="2025-06-05T19:31:23+07:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Jun 05 19:31:23 volumio go-librespot[2967]: time="2025-06-05T19:31:23+07:00" level=debug msg="renewing login5 access token" Jun 05 19:31:27 volumio go-librespot[2967]: time="2025-06-05T19:31:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Jun 05 19:31:33 volumio go-librespot[2967]: time="2025-06-05T19:31:33+07:00" level=debug msg="renewing login5 access token" Jun 05 19:31:36 volumio go-librespot[2967]: time="2025-06-05T19:31:36+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jun 05 19:31:39 volumio volumio[2754]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 19:31:39 volumio volumio[2754]: ConnectionError: Connection error in fetching pairing code Jun 05 19:31:39 volumio volumio[2754]: at PairingCodeRequestService._PairingCodeRequestService_getCodeAndEmit (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/PairingCodeRequestService.js:126:17) Jun 05 19:31:39 volumio volumio[2754]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Jun 05 19:31:39 volumio volumio[2754]: cause: TypeError: fetch failed Jun 05 19:31:39 volumio volumio[2754]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Jun 05 19:31:39 volumio volumio[2754]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 05 19:31:39 volumio volumio[2754]: at async PairingCodeRequestService._PairingCodeRequestService_getCodeAndEmit (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/PairingCodeRequestService.js:120:20) { Jun 05 19:31:39 volumio volumio[2754]: cause: ConnectTimeoutError: Connect Timeout Error Jun 05 19:31:39 volumio volumio[2754]: at onConnectTimeout (/data/plugins/music_service/youtube2/node_modules/undici/lib/core/connect.js:186:24) Jun 05 19:31:39 volumio volumio[2754]: at /data/plugins/music_service/youtube2/node_modules/undici/lib/core/connect.js:133:46 Jun 05 19:31:39 volumio volumio[2754]: at Immediate._onImmediate (/data/plugins/music_service/youtube2/node_modules/undici/lib/core/connect.js:174:9) Jun 05 19:31:39 volumio volumio[2754]: at process.processImmediate (node:internal/timers:478:21) { Jun 05 19:31:39 volumio volumio[2754]: code: 'UND_ERR_CONNECT_TIMEOUT' Jun 05 19:31:39 volumio volumio[2754]: } Jun 05 19:31:39 volumio volumio[2754]: }, Jun 05 19:31:39 volumio volumio[2754]: info: { Jun 05 19:31:39 volumio volumio[2754]: url: 'https://www.youtube.com/api/lounge/pairing/get_pairing_code?ctx=pair' Jun 05 19:31:39 volumio volumio[2754]: } Jun 05 19:31:39 volumio volumio[2754]: } Jun 05 19:31:39 volumio volumio[2754]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 19:31:39 volumio sudo[13609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-05 19:30' Jun 05 19:31:39 volumio sudo[13609]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"