-- Logs begin at Tue 2025-06-03 11:06:03 CEST, end at Tue 2025-06-03 13:48:17 CEST. -- Jun 03 13:47:09 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:47:09 jukebox volumio[5560]: info: Listing playlists Jun 03 13:47:09 jukebox volumio[5560]: info: Listing playlists Jun 03 13:47:09 jukebox go-librespot[5888]: time="2025-06-03T13:47:09+02:00" level=error msg="did not receive last pong from dealer, 150s passed" Jun 03 13:47:09 jukebox go-librespot[5888]: time="2025-06-03T13:47:09+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: i/o timeout" Jun 03 13:47:19 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:47:27 jukebox go-librespot[5888]: time="2025-06-03T13:47:27+02:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: i/o timeout" Jun 03 13:47:29 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:47:29 jukebox volumio[5560]: info: Listing playlists Jun 03 13:47:29 jukebox volumio[5560]: info: Listing playlists Jun 03 13:47:39 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:47:39 jukebox go-librespot[5888]: time="2025-06-03T13:47:39+02:00" level=error msg="did not receive last pong from dealer, 180s passed" Jun 03 13:47:39 jukebox go-librespot[5888]: time="2025-06-03T13:47:39+02:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: i/o timeout" Jun 03 13:47:47 jukebox volumiossh-tunnel[29551]: ssh: Could not resolve hostname eu4.myvolumio.org: Device or resource busy Jun 03 13:47:47 jukebox autossh[29553]: ssh exited with error status 255; restarting ssh Jun 03 13:47:47 jukebox autossh[29553]: starting ssh (count 10) Jun 03 13:47:47 jukebox autossh[29553]: ssh child pid is 19697 Jun 03 13:47:49 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:47:49 jukebox volumio[5560]: info: Listing playlists Jun 03 13:47:49 jukebox volumio[5560]: info: Listing playlists Jun 03 13:47:59 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:48:09 jukebox volumio[5560]: info: CoreCommandRouter::volumioGetState Jun 03 13:48:09 jukebox volumio[5560]: info: Listing playlists Jun 03 13:48:09 jukebox volumio[5560]: info: Listing playlists Jun 03 13:48:09 jukebox go-librespot[5888]: time="2025-06-03T13:48:09+02:00" level=error msg="did not receive last pong from dealer, 210s passed" Jun 03 13:48:09 jukebox go-librespot[5888]: time="2025-06-03T13:48:09+02:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": context deadline exceeded" Jun 03 13:48:09 jukebox go-librespot[5888]: time="2025-06-03T13:48:09+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: i/o timeout" Jun 03 13:48:10 jukebox go-librespot[5888]: time="2025-06-03T13:48:10+02:00" level=warning msg="failed fetching new endpoint for dealer" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=dealer\": dial tcp: lookup apresolve.spotify.com: i/o timeout" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 03 13:48:16 jukebox volumiossh-tunnel[29551]: ssh: Could not resolve hostname eu4.myvolumio.org: Device or resource busy Jun 03 13:48:16 jukebox autossh[29553]: ssh exited with error status 255; restarting ssh Jun 03 13:48:16 jukebox autossh[29553]: starting ssh (count 11) Jun 03 13:48:16 jukebox autossh[29553]: ssh child pid is 19797 Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="dealer connection opened" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="re-established dealer connection" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="received connection id: OGQzYzU5OWEtOWM3Ny00ODc0LTlkODgtMGQyYzI2Zjc2Yjg2K2RlYWxlcit0Y3A6Ly8wYWNhNTg4My5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQ0ZGOTE1MjE5MUJGREZEMjhGMjAyNzI2RUMzNjhCMUFCOTYwODk2N0U2MzBBMzY4RjFDNzFCQTg5NzRFRDY3OQ==" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="completed keyexchange" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="completed challenge" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=info msg="authenticated AP as 0c7vef030oojzgxvmrvs1e7x0" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="re-established accesspoint connection" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=trace msg="received accesspoint ping" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 03 13:48:16 jukebox volumiossh-tunnel[29551]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 03 13:48:16 jukebox go-librespot[5888]: time="2025-06-03T13:48:16+02:00" level=trace msg="received accesspoint pong ack" Jun 03 13:48:16 jukebox volumiossh-tunnel[29551]: Warning: remote port forwarding failed for listen port 8091 Jun 03 13:48:17 jukebox volumio[5560]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 13:48:17 jukebox volumio[5560]: RangeError: Maximum call stack size exceeded Jun 03 13:48:17 jukebox volumio[5560]: at MqttClient._shiftPingInterval (/volumio/node_modules/mqtt/lib/client.js:1014:52) Jun 03 13:48:17 jukebox volumio[5560]: at MqttClient._sendPacket (/volumio/node_modules/mqtt/lib/client.js:936:8) Jun 03 13:48:17 jukebox volumio[5560]: at deliver (/volumio/node_modules/mqtt/lib/client.js:217:12) Jun 03 13:48:17 jukebox volumio[5560]: at /volumio/node_modules/mqtt/lib/client.js:223:11 Jun 03 13:48:17 jukebox volumio[5560]: at sendPacket (/volumio/node_modules/mqtt/lib/client.js:86:5) Jun 03 13:48:17 jukebox volumio[5560]: at MqttClient._sendPacket (/volumio/node_modules/mqtt/lib/client.js:945:7) Jun 03 13:48:17 jukebox volumio[5560]: at deliver (/volumio/node_modules/mqtt/lib/client.js:217:12) Jun 03 13:48:17 jukebox volumio[5560]: at /volumio/node_modules/mqtt/lib/client.js:223:11 Jun 03 13:48:17 jukebox volumio[5560]: at sendPacket (/volumio/node_modules/mqtt/lib/client.js:86:5) Jun 03 13:48:17 jukebox volumio[5560]: at MqttClient._sendPacket (/volumio/node_modules/mqtt/lib/client.js:945:7) Jun 03 13:48:17 jukebox volumio[5560]: at deliver (/volumio/node_modules/mqtt/lib/client.js:217:12) Jun 03 13:48:17 jukebox volumio[5560]: at /volumio/node_modules/mqtt/lib/client.js:223:11 Jun 03 13:48:17 jukebox volumio[5560]: at sendPacket (/volumio/node_modules/mqtt/lib/client.js:86:5) Jun 03 13:48:17 jukebox volumio[5560]: at MqttClient._sendPacket (/volumio/node_modules/mqtt/lib/client.js:945:7) Jun 03 13:48:17 jukebox volumio[5560]: at deliver (/volumio/node_modules/mqtt/lib/client.js:217:12) Jun 03 13:48:17 jukebox volumio[5560]: at /volumio/node_modules/mqtt/lib/client.js:223:11 Jun 03 13:48:17 jukebox volumio[5560]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 13:48:17 jukebox sudo[19810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 13:47 Jun 03 13:48:17 jukebox sudo[19810]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"