-- Logs begin at Sat 2025-08-30 02:32:33 EEST, end at Thu 2025-09-04 10:14:31 EEST. -- Sep 04 10:13:01 primo volumio[3440]: info: Getting Spotify volume Sep 04 10:13:01 primo volumio[3440]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 04 10:13:01 primo volumio[3440]: info: CoreCommandRouter::volumioGetState Sep 04 10:13:01 primo volumio[3440]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Sep 04 10:13:25 primo go-librespot[12041]: time="2025-09-04T10:13:25+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:13:25 primo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 04 10:13:25 primo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 04 10:13:25 primo volumio[3440]: (node:3440) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 04 10:13:25 primo volumio[3440]: at connResetException (internal/errors.js:639:14) Sep 04 10:13:25 primo volumio[3440]: at Socket.socketOnEnd (_http_client.js:499:23) Sep 04 10:13:25 primo volumio[3440]: at Socket.emit (events.js:412:35) Sep 04 10:13:25 primo volumio[3440]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 04 10:13:25 primo volumio[3440]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 04 10:13:25 primo volumio[3440]: (node:3440) 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: 4) Sep 04 10:13:25 primo volumio[3440]: info: Connection to go-librespot Websocket closed Sep 04 10:13:28 primo volumio[3440]: info: Initializing connection to go-librespot Websocket Sep 04 10:13:28 primo volumio[3440]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 04 10:13:29 primo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 04 10:13:29 primo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 04 10:13:29 primo systemd[1]: Stopped go-librespot Daemon. Sep 04 10:13:29 primo systemd[1]: Started go-librespot Daemon. Sep 04 10:13:29 primo go-librespot[12142]: Librespot-go daemon starting... Sep 04 10:13:29 primo go-librespot[12142]: time="2025-09-04T10:13:29+03:00" level=info msg="generated new device id: 0424a68510cbe8d44e61a132abe303d827843560" Sep 04 10:13:29 primo go-librespot[12142]: time="2025-09-04T10:13:29+03:00" level=debug msg="stored credentials found for 8lifijmywkmtyo5nspkfeeenc" Sep 04 10:13:31 primo volumio[3440]: info: Initializing connection to go-librespot Websocket Sep 04 10:13:31 primo go-librespot[12142]: time="2025-09-04T10:13:31+03:00" level=debug msg="new websocket client" Sep 04 10:13:31 primo volumio[3440]: info: Connection to go-librespot Websocket established Sep 04 10:13:34 primo volumio[3440]: info: Getting Spotify volume Sep 04 10:13:34 primo volumio[3440]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 04 10:13:34 primo volumio[3440]: info: CoreCommandRouter::volumioGetState Sep 04 10:13:34 primo volumio[3440]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Sep 04 10:13:59 primo go-librespot[12142]: time="2025-09-04T10:13:59+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:13:59 primo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 04 10:13:59 primo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 04 10:13:59 primo volumio[3440]: (node:3440) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 04 10:13:59 primo volumio[3440]: at connResetException (internal/errors.js:639:14) Sep 04 10:13:59 primo volumio[3440]: at Socket.socketOnEnd (_http_client.js:499:23) Sep 04 10:13:59 primo volumio[3440]: at Socket.emit (events.js:412:35) Sep 04 10:13:59 primo volumio[3440]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 04 10:13:59 primo volumio[3440]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 04 10:13:59 primo volumio[3440]: (node:3440) 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: 5) Sep 04 10:13:59 primo volumio[3440]: info: Connection to go-librespot Websocket closed Sep 04 10:14:02 primo volumio[3440]: info: Initializing connection to go-librespot Websocket Sep 04 10:14:02 primo volumio[3440]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 04 10:14:02 primo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 04 10:14:02 primo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 04 10:14:02 primo systemd[1]: Stopped go-librespot Daemon. Sep 04 10:14:02 primo systemd[1]: Started go-librespot Daemon. Sep 04 10:14:02 primo go-librespot[12246]: Librespot-go daemon starting... Sep 04 10:14:02 primo go-librespot[12246]: time="2025-09-04T10:14:02+03:00" level=info msg="generated new device id: b9a58ecaf2c12094c3ac29ea39d5a622528d163c" Sep 04 10:14:02 primo go-librespot[12246]: time="2025-09-04T10:14:02+03:00" level=debug msg="stored credentials found for 8lifijmywkmtyo5nspkfeeenc" Sep 04 10:14:05 primo volumio[3440]: info: Initializing connection to go-librespot Websocket Sep 04 10:14:05 primo go-librespot[12246]: time="2025-09-04T10:14:05+03:00" level=debug msg="new websocket client" Sep 04 10:14:05 primo volumio[3440]: info: Connection to go-librespot Websocket established Sep 04 10:14:08 primo volumio[3440]: info: Getting Spotify volume Sep 04 10:14:08 primo volumio[3440]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 04 10:14:08 primo volumio[3440]: info: CoreCommandRouter::volumioGetState Sep 04 10:14:08 primo volumio[3440]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Sep 04 10:14:30 primo volumio[3440]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 10:14:30 primo volumio[3440]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Sep 04 10:14:30 primo volumio[3440]: code: 'auth/network-request-failed', Sep 04 10:14:30 primo volumio[3440]: a: null Sep 04 10:14:30 primo volumio[3440]: } Sep 04 10:14:30 primo volumio[3440]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 10:14:31 primo sudo[12363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 10:13 Sep 04 10:14:31 primo sudo[12363]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 12:21:26 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="0f77ca7c94b84efc5e990804c1669257"