-- Logs begin at Mon 2025-08-25 19:48:14 EEST, end at Thu 2025-09-04 10:57:28 EEST. -- Sep 04 10:56:00 volumio go-librespot[1409]: time="2025-09-04T10:56:00+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout" Sep 04 10:56:00 volumio volumio[829]: (node:829) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 04 10:56:00 volumio volumio[829]: at connResetException (internal/errors.js:607:14) Sep 04 10:56:00 volumio volumio[829]: at Socket.socketOnEnd (_http_client.js:493:23) Sep 04 10:56:00 volumio volumio[829]: at Socket.emit (events.js:327:22) Sep 04 10:56:00 volumio volumio[829]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 04 10:56:00 volumio volumio[829]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 04 10:56:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 04 10:56:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 04 10:56:00 volumio volumio[829]: (node:829) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 82) Sep 04 10:56:00 volumio volumio[829]: info: Connection to go-librespot Websocket closed Sep 04 10:56:03 volumio volumio[829]: info: Initializing connection to go-librespot Websocket Sep 04 10:56:03 volumio volumio[829]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 04 10:56:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 04 10:56:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Sep 04 10:56:03 volumio systemd[1]: Stopped go-librespot Daemon. Sep 04 10:56:03 volumio systemd[1]: Started go-librespot Daemon. Sep 04 10:56:03 volumio go-librespot[1505]: Librespot-go daemon starting... Sep 04 10:56:03 volumio go-librespot[1505]: time="2025-09-04T10:56:03+03:00" level=info msg="generated new device id: 3de8990c98e62d5df699b549f32216293d40c197" Sep 04 10:56:03 volumio go-librespot[1505]: time="2025-09-04T10:56:03+03:00" level=debug msg="stored credentials found for xz8b8wx06a1j6usb1arso44or" Sep 04 10:56:06 volumio volumio[829]: info: Initializing connection to go-librespot Websocket Sep 04 10:56:06 volumio go-librespot[1505]: time="2025-09-04T10:56:06+03:00" level=debug msg="new websocket client" Sep 04 10:56:06 volumio volumio[829]: info: Connection to go-librespot Websocket established Sep 04 10:56:09 volumio volumio[829]: info: Getting Spotify volume Sep 04 10:56:09 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 04 10:56:09 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Sep 04 10:56:09 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Sep 04 10:56:10 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 04 10:56:33 volumio go-librespot[1505]: time="2025-09-04T10:56:33+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout" Sep 04 10:56:33 volumio volumio[829]: (node:829) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 04 10:56:33 volumio volumio[829]: at connResetException (internal/errors.js:607:14) Sep 04 10:56:33 volumio volumio[829]: at Socket.socketOnEnd (_http_client.js:493:23) Sep 04 10:56:33 volumio volumio[829]: at Socket.emit (events.js:327:22) Sep 04 10:56:33 volumio volumio[829]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 04 10:56:33 volumio volumio[829]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 04 10:56:33 volumio volumio[829]: (node:829) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 83) Sep 04 10:56:33 volumio volumio[829]: info: Connection to go-librespot Websocket closed Sep 04 10:56:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 04 10:56:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 04 10:56:36 volumio volumio[829]: info: Initializing connection to go-librespot Websocket Sep 04 10:56:36 volumio volumio[829]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 04 10:56:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 04 10:56:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Sep 04 10:56:37 volumio systemd[1]: Stopped go-librespot Daemon. Sep 04 10:56:37 volumio systemd[1]: Started go-librespot Daemon. Sep 04 10:56:37 volumio go-librespot[1557]: Librespot-go daemon starting... Sep 04 10:56:37 volumio go-librespot[1557]: time="2025-09-04T10:56:37+03:00" level=info msg="generated new device id: 39bead1482c86491b76c5a097fa3453ef185fa56" Sep 04 10:56:37 volumio go-librespot[1557]: time="2025-09-04T10:56:37+03:00" level=debug msg="stored credentials found for xz8b8wx06a1j6usb1arso44or" Sep 04 10:56:39 volumio volumio[829]: info: Initializing connection to go-librespot Websocket Sep 04 10:56:39 volumio volumio[829]: info: Connection to go-librespot Websocket established Sep 04 10:56:39 volumio go-librespot[1557]: time="2025-09-04T10:56:39+03:00" level=debug msg="new websocket client" Sep 04 10:56:42 volumio volumio[829]: info: Getting Spotify volume Sep 04 10:56:43 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 04 10:56:43 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Sep 04 10:56:43 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Sep 04 10:56:43 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 04 10:57:07 volumio go-librespot[1557]: time="2025-09-04T10:57:07+03:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: i/o timeout" Sep 04 10:57:07 volumio volumio[829]: (node:829) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 04 10:57:07 volumio volumio[829]: at connResetException (internal/errors.js:607:14) Sep 04 10:57:07 volumio volumio[829]: at Socket.socketOnEnd (_http_client.js:493:23) Sep 04 10:57:07 volumio volumio[829]: at Socket.emit (events.js:327:22) Sep 04 10:57:07 volumio volumio[829]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 04 10:57:07 volumio volumio[829]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 04 10:57:07 volumio volumio[829]: (node:829) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 84) Sep 04 10:57:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 04 10:57:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 04 10:57:07 volumio volumio[829]: info: Connection to go-librespot Websocket closed Sep 04 10:57:10 volumio volumio[829]: info: Initializing connection to go-librespot Websocket Sep 04 10:57:10 volumio volumio[829]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 04 10:57:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 04 10:57:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Sep 04 10:57:10 volumio systemd[1]: Stopped go-librespot Daemon. Sep 04 10:57:10 volumio systemd[1]: Started go-librespot Daemon. Sep 04 10:57:10 volumio go-librespot[1650]: Librespot-go daemon starting... Sep 04 10:57:10 volumio go-librespot[1650]: time="2025-09-04T10:57:10+03:00" level=info msg="generated new device id: 4fd3d8e4708438e131a7424e71dac96fb714e08b" Sep 04 10:57:10 volumio go-librespot[1650]: time="2025-09-04T10:57:10+03:00" level=debug msg="stored credentials found for xz8b8wx06a1j6usb1arso44or" Sep 04 10:57:13 volumio volumio[829]: info: Initializing connection to go-librespot Websocket Sep 04 10:57:13 volumio volumio[829]: info: Connection to go-librespot Websocket established Sep 04 10:57:13 volumio go-librespot[1650]: time="2025-09-04T10:57:13+03:00" level=debug msg="new websocket client" Sep 04 10:57:16 volumio volumio[829]: info: Getting Spotify volume Sep 04 10:57:16 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 04 10:57:16 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Sep 04 10:57:16 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Sep 04 10:57:16 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 04 10:57:25 volumio volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 10:57:25 volumio volumio[829]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Sep 04 10:57:25 volumio volumio[829]: code: 'auth/network-request-failed', Sep 04 10:57:25 volumio volumio[829]: a: null Sep 04 10:57:25 volumio volumio[829]: } Sep 04 10:57:25 volumio volumio[829]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 10:57:28 volumio sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 10:56 Sep 04 10:57:28 volumio sudo[1696]: 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"