-- Logs begin at Tue 2025-09-02 13:08:24 CEST, end at Wed 2025-09-10 08:05:56 CEST. -- Sep 10 08:04:07 volumio go-librespot[1378]: time="2025-09-10T08:04:07+02:00" level=debug msg="fetched chunk 6/12, size: 524288" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:04:17 volumio ntpd[824]: Soliciting pool server 31.14.133.122 Sep 10 08:04:17 volumio go-librespot[1378]: time="2025-09-10T08:04:17+02:00" level=debug msg="fetched chunk 7/12, size: 524288" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:04:23 volumio ntpd[824]: Soliciting pool server 89.46.74.148 Sep 10 08:04:23 volumio go-librespot[1378]: time="2025-09-10T08:04:23+02:00" level=trace msg="received accesspoint ping" Sep 10 08:04:23 volumio go-librespot[1378]: time="2025-09-10T08:04:23+02:00" level=trace msg="received accesspoint pong ack" Sep 10 08:04:24 volumio go-librespot[1378]: time="2025-09-10T08:04:24+02:00" level=trace msg="sent dealer ping" Sep 10 08:04:24 volumio ntpd[824]: Soliciting pool server 2a00:6d41:10:1194::2 Sep 10 08:04:24 volumio ntpd[824]: Soliciting pool server 37.247.53.178 Sep 10 08:04:24 volumio go-librespot[1378]: time="2025-09-10T08:04:24+02:00" level=trace msg="received dealer pong" Sep 10 08:04:29 volumio go-librespot[1378]: time="2025-09-10T08:04:29+02:00" level=debug msg="fetched chunk 8/12, size: 524288" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:04:36 volumio volumio[920]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Sep 10 08:04:36 volumio volumio[920]: info: CoreCommandRouter::volumioGetState Sep 10 08:04:36 volumio volumio[920]: info: CorePlayQueue::getTrack 0 Sep 10 08:04:40 volumio go-librespot[1378]: time="2025-09-10T08:04:40+02:00" level=debug msg="fetched chunk 9/12, size: 524288" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:04:51 volumio go-librespot[1378]: time="2025-09-10T08:04:51+02:00" level=debug msg="fetched chunk 10/12, size: 524288" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:04:54 volumio go-librespot[1378]: time="2025-09-10T08:04:54+02:00" level=trace msg="sent dealer ping" Sep 10 08:04:54 volumio go-librespot[1378]: time="2025-09-10T08:04:54+02:00" level=trace msg="received dealer pong" Sep 10 08:05:00 volumio volumio[920]: error: Cannot refresh My Volumio Token: Sep 10 08:05:01 volumio go-librespot[1378]: time="2025-09-10T08:05:01+02:00" level=debug msg="fetched chunk 11/12, size: 524288" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:05:07 volumio go-librespot[1378]: time="2025-09-10T08:05:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Sep 10 08:05:07 volumio go-librespot[1378]: time="2025-09-10T08:05:07+02:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIVbBVwTUINi1" Sep 10 08:05:12 volumio go-librespot[1378]: time="2025-09-10T08:05:12+02:00" level=debug msg="fetched chunk 12/12, size: 417412" uri="spotify:track:7oJtUqvumujVKarcXa1SPj" Sep 10 08:05:24 volumio go-librespot[1378]: time="2025-09-10T08:05:24+02:00" level=trace msg="sent dealer ping" Sep 10 08:05:24 volumio go-librespot[1378]: time="2025-09-10T08:05:24+02:00" level=trace msg="received dealer pong" Sep 10 08:05:48 volumio volumio[920]: info: CorePlayQueue::getTrack 0 Sep 10 08:05:48 volumio volumio[920]: info: CorePlayQueue::getTrack 1 Sep 10 08:05:48 volumio volumio[920]: info: Prefetching next song Sep 10 08:05:48 volumio volumio[920]: info: [1757484348396] ControllerSpotify::prefetch Sep 10 08:05:48 volumio volumio[920]: info: Sending Spotify command with payload to local API: /player/add_to_queue Sep 10 08:05:52 volumio volumio[920]: info: CoreStateMachine::startPlaybackTimer Sep 10 08:05:52 volumio volumio[920]: info: CorePlayQueue::getTrack 1 Sep 10 08:05:53 volumio volumio[920]: info: CoreStateMachine::pushState Sep 10 08:05:53 volumio volumio[920]: info: CorePlayQueue::getTrack 1 Sep 10 08:05:53 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 08:05:53 volumio volumio[920]: info: CoreCommandRouter::volumioPushState Sep 10 08:05:53 volumio volumio[920]: info: MRS: Pushing multiroomSync output update for this device Sep 10 08:05:53 volumio volumio[920]: info: MRS: Pushing multiroomSync output Sep 10 08:05:53 volumio volumio[920]: info: CoreCommandRouter::volumioGetState Sep 10 08:05:53 volumio volumio[920]: info: CorePlayQueue::getTrack 1 Sep 10 08:05:54 volumio go-librespot[1378]: time="2025-09-10T08:05:54+02:00" level=trace msg="sent dealer ping" Sep 10 08:05:54 volumio go-librespot[1378]: time="2025-09-10T08:05:54+02:00" level=trace msg="received dealer pong" Sep 10 08:05:56 volumio volumio[920]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 08:05:56 volumio volumio[920]: Error: connect ETIMEDOUT 146.75.62.79:80 Sep 10 08:05:56 volumio volumio[920]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 10 08:05:56 volumio volumio[920]: errno: -110, Sep 10 08:05:56 volumio volumio[920]: code: 'ETIMEDOUT', Sep 10 08:05:56 volumio volumio[920]: syscall: 'connect', Sep 10 08:05:56 volumio volumio[920]: address: '146.75.62.79', Sep 10 08:05:56 volumio volumio[920]: port: 80 Sep 10 08:05:56 volumio volumio[920]: } Sep 10 08:05:56 volumio volumio[920]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 08:05:56 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 08:04 Sep 10 08:05:56 volumio sudo[2586]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"