-- Logs begin at Wed 2026-02-11 07:16:18 AEST, end at Thu 2026-02-12 14:56:24 AEST. -- Feb 12 14:55:04 garage.lan go-librespot[15161]: time="2026-02-12T14:55:04+10:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070 (error: dial tcp: lookup ap-guc3.spotify.com: i/o timeout), retrying with a different AP" Feb 12 14:55:07 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:55:07 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:55:07 garage.lan volumio[837]: info: Listing playlists Feb 12 14:55:07 garage.lan volumio[837]: info: Listing playlists Feb 12 14:55:13 garage.lan go-librespot[15161]: time="2026-02-12T14:55:13+10:00" level=error msg="did not receive last pong from dealer, 138s passed" Feb 12 14:55:17 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:55:17 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:55:27 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:55:27 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:55:27 garage.lan volumio[837]: info: Listing playlists Feb 12 14:55:27 garage.lan volumio[837]: info: Listing playlists Feb 12 14:55:34 garage.lan go-librespot[15161]: time="2026-02-12T14:55:34+10:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443 (error: dial tcp: lookup ap-gew1.spotify.com: i/o timeout), retrying with a different AP" Feb 12 14:55:37 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:55:37 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:55:43 garage.lan go-librespot[15161]: time="2026-02-12T14:55:43+10:00" level=error msg="did not receive last pong from dealer, 168s passed" Feb 12 14:55:47 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:55:47 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:55:47 garage.lan volumio[837]: info: Listing playlists Feb 12 14:55:47 garage.lan volumio[837]: info: Listing playlists Feb 12 14:55:57 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:55:57 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:56:03 garage.lan go-librespot[15161]: time="2026-02-12T14:56:03+10:00" level=info msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:56:03 garage.lan go-librespot[15161]: time="2026-02-12T14:56:03+10:00" level=debug msg="completed keyexchange" Feb 12 14:56:03 garage.lan go-librespot[15161]: time="2026-02-12T14:56:03+10:00" level=debug msg="completed challenge" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="authenticated as 1234130734" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="re-established accesspoint connection" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="autoplay enabled: false" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="re-established dealer connection" Feb 12 14:56:04 garage.lan go-librespot[15161]: time="2026-02-12T14:56:04+10:00" level=debug msg="received connection id: YjFhMGVjZmItNTQyNS00MGIwLTk2NDAtM2FjYWQ3NTlhZmViK2RlYWxlcit0Y3A6Ly8wYWIxNDk4Ni5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArMzExQzhGOUEwNDA1ODk5QkFBODA4MTk3RDA2QzUyNTNBN0I1RTQ5MTQwQ0Q3MTVEQTMzMkZEMkRFOEVGM0I1QQ==" Feb 12 14:56:04 garage.lan volumiossh-tunnel[18745]: ssh: connect to host eu2.myvolumio.org port 2222: Connection timed out Feb 12 14:56:04 garage.lan autossh[18746]: ssh exited with error status 255; restarting ssh Feb 12 14:56:04 garage.lan autossh[18746]: starting ssh (count 2) Feb 12 14:56:04 garage.lan autossh[18746]: ssh child pid is 19060 Feb 12 14:56:05 garage.lan go-librespot[15161]: time="2026-02-12T14:56:05+10:00" level=debug msg="put connect state because NEW_DEVICE" Feb 12 14:56:06 garage.lan volumiossh-tunnel[18745]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Feb 12 14:56:07 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:56:07 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:56:07 garage.lan volumio[837]: info: Listing playlists Feb 12 14:56:07 garage.lan volumio[837]: info: Listing playlists Feb 12 14:56:17 garage.lan volumio[837]: info: CoreCommandRouter::volumioGetState Feb 12 14:56:17 garage.lan volumio[837]: info: CorePlayQueue::getTrack 0 Feb 12 14:56:23 garage.lan volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:56:23 garage.lan volumio[837]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Feb 12 14:56:23 garage.lan volumio[837]: code: 'auth/network-request-failed', Feb 12 14:56:23 garage.lan volumio[837]: a: null Feb 12 14:56:23 garage.lan volumio[837]: } Feb 12 14:56:23 garage.lan volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:56:24 garage.lan sudo[19104]: volumio : unable to resolve host garage.lan Feb 12 14:56:24 garage.lan sudo[19104]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 12 14:56:24 garage.lan sudo[19104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-12 14:55 Feb 12 14:56:24 garage.lan sudo[19104]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"