-- Logs begin at Fri 2025-12-05 23:22:59 JST, end at Sat 2025-12-13 14:24:53 JST. -- Dec 13 14:23:02 volumio go-librespot[30467]: time="2025-12-13T14:23:02+09: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\": net/http: TLS handshake timeout" Dec 13 14:23:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 13 14:23:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 13 14:23:02 volumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 13 14:23:02 volumio volumio[1018]: at connResetException (internal/errors.js:607:14) Dec 13 14:23:02 volumio volumio[1018]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 13 14:23:02 volumio volumio[1018]: at Socket.emit (events.js:327:22) Dec 13 14:23:02 volumio volumio[1018]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 13 14:23:02 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 13 14:23:02 volumio volumio[1018]: (node:1018) 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) Dec 13 14:23:02 volumio volumio[1018]: info: Connection to go-librespot Websocket closed Dec 13 14:23:05 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:23:05 volumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 13 14:23:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 13 14:23:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 13 14:23:05 volumio systemd[1]: Stopped go-librespot Daemon. Dec 13 14:23:05 volumio systemd[1]: Started go-librespot Daemon. Dec 13 14:23:05 volumio go-librespot[30553]: Librespot-go daemon starting... Dec 13 14:23:05 volumio go-librespot[30553]: time="2025-12-13T14:23:05+09:00" level=info msg="generated new device id: 578bcbe12cc0c4620a2e6578010be1fccd694e3e" Dec 13 14:23:05 volumio go-librespot[30553]: time="2025-12-13T14:23:05+09:00" level=debug msg="stored credentials found for kuristar8" Dec 13 14:23:08 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:23:08 volumio go-librespot[30553]: time="2025-12-13T14:23:08+09:00" level=debug msg="new websocket client" Dec 13 14:23:08 volumio volumio[1018]: info: Connection to go-librespot Websocket established Dec 13 14:23:11 volumio volumio[1018]: info: Getting Spotify volume Dec 13 14:23:11 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 13 14:23:11 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 13 14:23:11 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 13 14:23:11 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 13 14:23:37 volumio go-librespot[30553]: time="2025-12-13T14:23:37+09: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\": net/http: TLS handshake timeout" Dec 13 14:23:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 13 14:23:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 13 14:23:37 volumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 13 14:23:37 volumio volumio[1018]: at connResetException (internal/errors.js:607:14) Dec 13 14:23:37 volumio volumio[1018]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 13 14:23:37 volumio volumio[1018]: at Socket.emit (events.js:327:22) Dec 13 14:23:37 volumio volumio[1018]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 13 14:23:37 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 13 14:23:37 volumio volumio[1018]: (node:1018) 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: 6) Dec 13 14:23:37 volumio volumio[1018]: info: Connection to go-librespot Websocket closed Dec 13 14:23:40 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:23:40 volumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 13 14:23:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 13 14:23:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 13 14:23:40 volumio systemd[1]: Stopped go-librespot Daemon. Dec 13 14:23:40 volumio systemd[1]: Started go-librespot Daemon. Dec 13 14:23:40 volumio go-librespot[30736]: Librespot-go daemon starting... Dec 13 14:23:40 volumio go-librespot[30736]: time="2025-12-13T14:23:40+09:00" level=info msg="generated new device id: 74a4db4716c8cc28aca2a5c785de6379bd1b6cd2" Dec 13 14:23:40 volumio go-librespot[30736]: time="2025-12-13T14:23:40+09:00" level=debug msg="stored credentials found for kuristar8" Dec 13 14:23:43 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:23:43 volumio go-librespot[30736]: time="2025-12-13T14:23:43+09:00" level=debug msg="new websocket client" Dec 13 14:23:43 volumio volumio[1018]: info: Connection to go-librespot Websocket established Dec 13 14:23:46 volumio volumio[1018]: info: Getting Spotify volume Dec 13 14:23:46 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 13 14:23:46 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 13 14:23:46 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 13 14:23:46 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 13 14:23:59 volumio go-librespot[30736]: time="2025-12-13T14:23:59+09: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\": net/http: TLS handshake timeout" Dec 13 14:23:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 13 14:23:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 13 14:23:59 volumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 13 14:23:59 volumio volumio[1018]: at connResetException (internal/errors.js:607:14) Dec 13 14:23:59 volumio volumio[1018]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 13 14:23:59 volumio volumio[1018]: at Socket.emit (events.js:327:22) Dec 13 14:23:59 volumio volumio[1018]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 13 14:23:59 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 13 14:23:59 volumio volumio[1018]: (node:1018) 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: 8) Dec 13 14:23:59 volumio volumio[1018]: info: Connection to go-librespot Websocket closed Dec 13 14:24:02 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:24:02 volumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 13 14:24:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 13 14:24:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 13 14:24:02 volumio systemd[1]: Stopped go-librespot Daemon. Dec 13 14:24:02 volumio systemd[1]: Started go-librespot Daemon. Dec 13 14:24:02 volumio go-librespot[30823]: Librespot-go daemon starting... Dec 13 14:24:02 volumio go-librespot[30823]: time="2025-12-13T14:24:02+09:00" level=info msg="generated new device id: 21f87e0925c05730ad9140f2df46ef7353e55eff" Dec 13 14:24:02 volumio go-librespot[30823]: time="2025-12-13T14:24:02+09:00" level=debug msg="stored credentials found for kuristar8" Dec 13 14:24:05 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:24:05 volumio go-librespot[30823]: time="2025-12-13T14:24:05+09:00" level=debug msg="new websocket client" Dec 13 14:24:05 volumio volumio[1018]: info: Connection to go-librespot Websocket established Dec 13 14:24:08 volumio volumio[1018]: info: Getting Spotify volume Dec 13 14:24:08 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 13 14:24:08 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 13 14:24:08 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 13 14:24:08 volumio volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 13 14:24:08 volumio go-librespot[30823]: time="2025-12-13T14:24:08+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 13 14:24:08 volumio go-librespot[30823]: time="2025-12-13T14:24:08+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 13 14:24:08 volumio go-librespot[30823]: time="2025-12-13T14:24:08+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 13 14:24:08 volumio go-librespot[30823]: time="2025-12-13T14:24:08+09:00" level=debug msg="zeroconf server listening on port 37957" Dec 13 14:24:29 volumio go-librespot[30823]: time="2025-12-13T14:24:29+09:00" level=debug msg="obtained new client token: AAA0vSKL9mVG24MhMI0n91tJpXwT/s3svXQqYi+Mvofp6mo6scoJq1kBKeT0qW2xAdUTXapjCjQy+E+RLOkATVXIVZdDyZoUZp/Dxfpdn3GgY9McRczRP22ZQ4SeDmvJ/8XO3ujKy3nwW7PEHVnSkXPjsXrWpXEJgXk474nL0rdUybouGnCenleCuEXM0R81eR5ZfDR6lKuuq1nfB/YqMugACb/82FcZuUUdZ/Ryu4EX08ehcC1vHrCwwN4=" Dec 13 14:24:30 volumio go-librespot[30823]: time="2025-12-13T14:24:30+09:00" level=debug msg="completed keyexchange" Dec 13 14:24:30 volumio go-librespot[30823]: time="2025-12-13T14:24:30+09:00" level=debug msg="completed challenge" Dec 13 14:24:46 volumio go-librespot[30823]: time="2025-12-13T14:24:46+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed recevining Login response packet: failed reading packet header: read tcp 192.168.3.151:41632->104.199.241.202:4070: read: connection reset by peer" Dec 13 14:24:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 13 14:24:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 13 14:24:46 volumio volumio[1018]: (node:1018) UnhandledPromiseRejectionWarning: Error: socket hang up Dec 13 14:24:46 volumio volumio[1018]: at connResetException (internal/errors.js:607:14) Dec 13 14:24:46 volumio volumio[1018]: at Socket.socketOnEnd (_http_client.js:493:23) Dec 13 14:24:46 volumio volumio[1018]: at Socket.emit (events.js:327:22) Dec 13 14:24:46 volumio volumio[1018]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 13 14:24:46 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 13 14:24:46 volumio volumio[1018]: (node:1018) 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: 10) Dec 13 14:24:46 volumio volumio[1018]: info: Connection to go-librespot Websocket closed Dec 13 14:24:49 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:24:49 volumio volumio[1018]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 13 14:24:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 13 14:24:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 13 14:24:49 volumio systemd[1]: Stopped go-librespot Daemon. Dec 13 14:24:49 volumio systemd[1]: Started go-librespot Daemon. Dec 13 14:24:49 volumio go-librespot[31060]: Librespot-go daemon starting... Dec 13 14:24:49 volumio go-librespot[31060]: time="2025-12-13T14:24:49+09:00" level=info msg="generated new device id: 5a9b739aa192c0a5026eb20b2cc834867c7047d3" Dec 13 14:24:49 volumio go-librespot[31060]: time="2025-12-13T14:24:49+09:00" level=debug msg="stored credentials found for kuristar8" Dec 13 14:24:52 volumio volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 13 14:24:52 volumio go-librespot[31060]: time="2025-12-13T14:24:52+09:00" level=debug msg="new websocket client" Dec 13 14:24:52 volumio volumio[1018]: info: Connection to go-librespot Websocket established Dec 13 14:24:53 volumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 14:24:53 volumio volumio[1018]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Dec 13 14:24:53 volumio volumio[1018]: code: 'auth/network-request-failed', Dec 13 14:24:53 volumio volumio[1018]: a: null Dec 13 14:24:53 volumio volumio[1018]: } Dec 13 14:24:53 volumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 14:24:53 volumio sudo[31089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-13 14:23 Dec 13 14:24:53 volumio sudo[31089]: 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"