-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-06-08 16:18:32 CEST. -- Jun 08 16:17:09 volumio-under go-librespot[1418]: time="2025-06-08T16:17:09+02:00" level=error msg="did not receive last pong from dealer, 411s passed" Jun 08 16:17:25 volumio-under volumiossh-tunnel[9536]: Connection to 185.14.187.123 port 2222 timed out Jun 08 16:17:25 volumio-under autossh[9538]: ssh exited with error status 255; restarting ssh Jun 08 16:17:25 volumio-under autossh[9538]: starting ssh (count 2) Jun 08 16:17:25 volumio-under autossh[9538]: ssh child pid is 9773 Jun 08 16:17:25 volumio-under volumio[905]: BT PLUGIN MESSAGE: Duration is 186000 Jun 08 16:17:25 volumio-under volumio[905]: info: CoreCommandRouter::servicePushState Jun 08 16:17:25 volumio-under volumio[905]: info: CoreStateMachine::pushState Jun 08 16:17:25 volumio-under volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:17:25 volumio-under volumio[905]: info: CoreCommandRouter::volumioPushState Jun 08 16:17:25 volumio-under volumio[905]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:17:25 volumio-under volumio[905]: info: MRS: Pushing multiroomSync output Jun 08 16:17:25 volumio-under volumio[905]: info: CoreCommandRouter::volumioGetState Jun 08 16:17:25 volumio-under volumio[905]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jun 08 16:17:26 volumio-under volumio[905]: info: CoreCommandRouter::servicePushState Jun 08 16:17:26 volumio-under volumio[905]: info: CoreStateMachine::pushState Jun 08 16:17:26 volumio-under volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:17:26 volumio-under volumio[905]: info: CoreCommandRouter::volumioPushState Jun 08 16:17:26 volumio-under volumio[905]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:17:26 volumio-under volumio[905]: info: MRS: Pushing multiroomSync output Jun 08 16:17:26 volumio-under volumio[905]: info: CoreCommandRouter::volumioGetState Jun 08 16:17:26 volumio-under volumio[905]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jun 08 16:17:28 volumio-under go-librespot[1418]: time="2025-06-08T16:17:28+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.133:59164->34.158.255.62:443: read: connection reset by peer" Jun 08 16:17:33 volumio-under go-librespot[1418]: time="2025-06-08T16:17:33+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Jun 08 16:17:33 volumio-under volumiossh-tunnel[9536]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Jun 08 16:17:33 volumio-under go-librespot[1418]: time="2025-06-08T16:17:33+02:00" level=debug msg="completed keyexchange" Jun 08 16:17:33 volumio-under go-librespot[1418]: time="2025-06-08T16:17:33+02:00" level=debug msg="completed challenge" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=info msg="authenticated AP as stratocaster74" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=debug msg="re-established accesspoint connection" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=trace msg="received accesspoint ping" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 08 16:17:35 volumio-under go-librespot[1418]: time="2025-06-08T16:17:35+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 08 16:17:39 volumio-under go-librespot[1418]: time="2025-06-08T16:17:39+02:00" level=error msg="did not receive last pong from dealer, 441s passed" Jun 08 16:18:09 volumio-under go-librespot[1418]: time="2025-06-08T16:18:09+02:00" level=error msg="did not receive last pong ack from accesspoint, 182s passed" Jun 08 16:18:09 volumio-under go-librespot[1418]: time="2025-06-08T16:18:09+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.133:39804->104.199.65.9:80: use of closed network connection" Jun 08 16:18:09 volumio-under go-librespot[1418]: time="2025-06-08T16:18:09+02:00" level=error msg="did not receive last pong from dealer, 471s passed" Jun 08 16:18:10 volumio-under ntpd[688]: 83.150.25.25 local addr 192.168.1.133 -> Jun 08 16:18:11 volumio-under ntpd[688]: Soliciting pool server 109.233.182.115 Jun 08 16:18:19 volumio-under go-librespot[1418]: time="2025-06-08T16:18:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 08 16:18:29 volumio-under volumio[905]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 16:18:29 volumio-under volumio[905]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Jun 08 16:18:29 volumio-under volumio[905]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 08 16:18:29 volumio-under volumio[905]: errno: -3007, Jun 08 16:18:29 volumio-under volumio[905]: code: 'ENOTFOUND', Jun 08 16:18:29 volumio-under volumio[905]: syscall: 'getaddrinfo', Jun 08 16:18:29 volumio-under volumio[905]: hostname: 'lastfm.freetls.fastly.net' Jun 08 16:18:29 volumio-under volumio[905]: } Jun 08 16:18:29 volumio-under volumio[905]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 16:18:32 volumio-under sudo[9966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 16:17 Jun 08 16:18:32 volumio-under sudo[9966]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"