-- Logs begin at Fri 2025-07-04 03:28:44 CEST, end at Mon 2025-07-07 01:28:43 CEST. -- Jul 07 01:27:00 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:27:00 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:27:00 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:27:00 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:27:00 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:27:05 volumiorpi3a go-librespot[17297]: time="2025-07-07T01:27:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:27:05 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:27:05 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:27:05 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:27:05 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:27:05 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:27:05 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:27:05 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:27:05 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:27:05 volumiorpi3a volumio[953]: (node:953) 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: 69) Jul 07 01:27:05 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:27:08 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:08 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:27:08 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:27:08 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Jul 07 01:27:08 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:27:08 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:27:08 volumiorpi3a go-librespot[17319]: Librespot-go daemon starting... Jul 07 01:27:08 volumiorpi3a go-librespot[17319]: time="2025-07-07T01:27:08+02:00" level=info msg="generated new device id: a40e8c42b88b1611841389a64b5e4578b0537abe" Jul 07 01:27:08 volumiorpi3a go-librespot[17319]: time="2025-07-07T01:27:08+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:27:11 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:11 volumiorpi3a go-librespot[17319]: time="2025-07-07T01:27:11+02:00" level=debug msg="new websocket client" Jul 07 01:27:11 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:27:14 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:27:14 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:27:14 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:27:14 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:27:14 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:27:18 volumiorpi3a go-librespot[17319]: time="2025-07-07T01:27:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:27:18 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:27:18 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:27:18 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:27:18 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:27:18 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:27:18 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:27:18 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:27:18 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:27:18 volumiorpi3a volumio[953]: (node:953) 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: 70) Jul 07 01:27:18 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:27:21 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:21 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:27:21 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:27:21 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Jul 07 01:27:21 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:27:21 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:27:21 volumiorpi3a go-librespot[17343]: Librespot-go daemon starting... Jul 07 01:27:21 volumiorpi3a go-librespot[17343]: time="2025-07-07T01:27:21+02:00" level=info msg="generated new device id: 38413108860ead735816f3e2580815c030f157f1" Jul 07 01:27:21 volumiorpi3a go-librespot[17343]: time="2025-07-07T01:27:21+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:27:24 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:24 volumiorpi3a go-librespot[17343]: time="2025-07-07T01:27:24+02:00" level=debug msg="new websocket client" Jul 07 01:27:24 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:27:27 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:27:27 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:27:27 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:27:27 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:27:27 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:27:31 volumiorpi3a go-librespot[17343]: time="2025-07-07T01:27:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:27:31 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:27:31 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:27:31 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:27:31 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:27:31 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:27:31 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:27:31 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:27:31 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:27:31 volumiorpi3a volumio[953]: (node:953) 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: 72) Jul 07 01:27:31 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:27:34 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:34 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:27:34 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:27:34 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Jul 07 01:27:34 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:27:34 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:27:34 volumiorpi3a go-librespot[17420]: Librespot-go daemon starting... Jul 07 01:27:34 volumiorpi3a go-librespot[17420]: time="2025-07-07T01:27:34+02:00" level=info msg="generated new device id: 9c491b4e14f374ada83a95faec703fbbc4c545e8" Jul 07 01:27:34 volumiorpi3a go-librespot[17420]: time="2025-07-07T01:27:34+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:27:37 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:37 volumiorpi3a go-librespot[17420]: time="2025-07-07T01:27:37+02:00" level=debug msg="new websocket client" Jul 07 01:27:37 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:27:40 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:27:40 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:27:40 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:27:40 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:27:40 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:27:44 volumiorpi3a go-librespot[17420]: time="2025-07-07T01:27:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:27:44 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:27:44 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:27:44 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:27:44 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:27:44 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:27:44 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:27:44 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:27:44 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:27:44 volumiorpi3a volumio[953]: (node:953) 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: 73) Jul 07 01:27:44 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:27:47 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:47 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:27:48 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:27:48 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Jul 07 01:27:48 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:27:48 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:27:48 volumiorpi3a go-librespot[17442]: Librespot-go daemon starting... Jul 07 01:27:48 volumiorpi3a go-librespot[17442]: time="2025-07-07T01:27:48+02:00" level=info msg="generated new device id: d4c1c547c9dbf9f6a233e6e86e0a9ee8947285ee" Jul 07 01:27:48 volumiorpi3a go-librespot[17442]: time="2025-07-07T01:27:48+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:27:50 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:27:50 volumiorpi3a go-librespot[17442]: time="2025-07-07T01:27:50+02:00" level=debug msg="new websocket client" Jul 07 01:27:50 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:27:53 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:27:53 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:27:53 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:27:53 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:27:53 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:27:58 volumiorpi3a go-librespot[17442]: time="2025-07-07T01:27:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:27:58 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:27:58 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:27:58 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:27:58 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:27:58 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:27:58 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:27:58 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:27:58 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:27:58 volumiorpi3a volumio[953]: (node:953) 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: 75) Jul 07 01:27:58 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:28:01 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:01 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:28:01 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:28:01 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Jul 07 01:28:01 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:28:01 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:28:01 volumiorpi3a go-librespot[17467]: Librespot-go daemon starting... Jul 07 01:28:01 volumiorpi3a go-librespot[17467]: time="2025-07-07T01:28:01+02:00" level=info msg="generated new device id: d8f15d12959852e26c54ca7a52902d4ac8cdd9bd" Jul 07 01:28:01 volumiorpi3a go-librespot[17467]: time="2025-07-07T01:28:01+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:28:04 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:04 volumiorpi3a go-librespot[17467]: time="2025-07-07T01:28:04+02:00" level=debug msg="new websocket client" Jul 07 01:28:04 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:28:07 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:28:07 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:28:07 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:28:07 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:28:07 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:28:11 volumiorpi3a go-librespot[17467]: time="2025-07-07T01:28:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:28:11 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:28:11 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:28:11 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:28:11 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:28:11 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:28:11 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:28:11 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:28:11 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:28:11 volumiorpi3a volumio[953]: (node:953) 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: 76) Jul 07 01:28:11 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:28:14 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:14 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:28:14 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:28:14 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Jul 07 01:28:14 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:28:14 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:28:14 volumiorpi3a go-librespot[17499]: Librespot-go daemon starting... Jul 07 01:28:14 volumiorpi3a go-librespot[17499]: time="2025-07-07T01:28:14+02:00" level=info msg="generated new device id: d62fcb0a2cd6e736d4feed671124494f152c032a" Jul 07 01:28:14 volumiorpi3a go-librespot[17499]: time="2025-07-07T01:28:14+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:28:17 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:17 volumiorpi3a go-librespot[17499]: time="2025-07-07T01:28:17+02:00" level=debug msg="new websocket client" Jul 07 01:28:17 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:28:20 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:28:20 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:28:20 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:28:20 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:28:20 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:28:24 volumiorpi3a go-librespot[17499]: time="2025-07-07T01:28:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": net/http: TLS handshake timeout" Jul 07 01:28:24 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:28:24 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:28:24 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:28:24 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:28:24 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:28:24 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:28:24 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:28:24 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:28:24 volumiorpi3a volumio[953]: (node:953) 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: 78) Jul 07 01:28:24 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:28:27 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:27 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:28:27 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:28:27 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Jul 07 01:28:27 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:28:27 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:28:27 volumiorpi3a go-librespot[17527]: Librespot-go daemon starting... Jul 07 01:28:27 volumiorpi3a go-librespot[17527]: time="2025-07-07T01:28:27+02:00" level=info msg="generated new device id: e3e12cb70c516ce9a39348321d108c45d3538b97" Jul 07 01:28:27 volumiorpi3a go-librespot[17527]: time="2025-07-07T01:28:27+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:28:30 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:30 volumiorpi3a go-librespot[17527]: time="2025-07-07T01:28:30+02:00" level=debug msg="new websocket client" Jul 07 01:28:30 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:28:33 volumiorpi3a volumio[953]: info: Getting Spotify volume Jul 07 01:28:33 volumiorpi3a volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 07 01:28:33 volumiorpi3a volumio[953]: info: CoreCommandRouter::volumioGetState Jul 07 01:28:33 volumiorpi3a volumio[953]: info: CorePlayQueue::getTrack 0 Jul 07 01:28:33 volumiorpi3a volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Jul 07 01:28:35 volumiorpi3a go-librespot[17527]: time="2025-07-07T01:28:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": tls: failed to verify certificate: x509: certificate is valid for funbox, not clienttoken.spotify.com" Jul 07 01:28:35 volumiorpi3a volumio[953]: (node:953) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 01:28:35 volumiorpi3a volumio[953]: at connResetException (internal/errors.js:607:14) Jul 07 01:28:35 volumiorpi3a volumio[953]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 01:28:35 volumiorpi3a volumio[953]: at Socket.emit (events.js:327:22) Jul 07 01:28:35 volumiorpi3a volumio[953]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 01:28:35 volumiorpi3a volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 01:28:35 volumiorpi3a volumio[953]: (node:953) 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: 79) Jul 07 01:28:35 volumiorpi3a systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 01:28:35 volumiorpi3a systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 01:28:35 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket closed Jul 07 01:28:38 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:38 volumiorpi3a volumio[953]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 07 01:28:38 volumiorpi3a systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 01:28:38 volumiorpi3a systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Jul 07 01:28:38 volumiorpi3a systemd[1]: Stopped go-librespot Daemon. Jul 07 01:28:38 volumiorpi3a systemd[1]: Started go-librespot Daemon. Jul 07 01:28:38 volumiorpi3a go-librespot[17590]: Librespot-go daemon starting... Jul 07 01:28:38 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:38+02:00" level=info msg="generated new device id: 2194a9bae6d1c48a332055bc4c86e37caa88c793" Jul 07 01:28:38 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:38+02:00" level=debug msg="stored credentials found for cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:28:38 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:38+02:00" level=debug msg="obtained new client token: AAAyBzOSSKok8YRUvYsZDAJL/Qx87oRVtgXH+9yj1yl5KbxItG+CYOJ94sYSUdC9vBnpkgmhfhpQ/bfrybKamxim3f7nDWRWfoTum/lkcVM2jpptbJPDEHhk2gxru2mPumV8QEChL6k8AUsoqXzIsG8wNTiC2sqbyoKzvr2NIhw+4qMcFAprhnUGQDbviumWklJvAJXlhnUowVs/SuzPH9J8DKtOdk8zXmPRfOctOUHDO2HEddkZjVYR2uT57vQ=" Jul 07 01:28:38 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="completed keyexchange" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="completed challenge" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="authenticated as cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="authenticated as cv4xpb2m9qb5j9xbcqlwky7s1" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="dealer connection opened" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 07 01:28:39 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:39+02:00" level=debug msg="received connection id: ZWQ4MTVjZWItNWFhZi00YjNlLWI4MmQtMmVhODNlMjM2Yzc5K2RlYWxlcit0Y3A6Ly8wYWIxNTA1YS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMDQ4OUYzOENDRjMxRDMyMTY2OUMzRDNDMzgzREY4MUUwRTRGMDczMzY4RUM2M0MxMDVEMkFDNUIzRUVCQkRBQg==" Jul 07 01:28:40 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:40+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 07 01:28:41 volumiorpi3a volumio[953]: info: Initializing connection to go-librespot Websocket Jul 07 01:28:41 volumiorpi3a go-librespot[17590]: time="2025-07-07T01:28:41+02:00" level=debug msg="new websocket client" Jul 07 01:28:41 volumiorpi3a volumio[953]: info: Connection to go-librespot Websocket established Jul 07 01:28:42 volumiorpi3a volumio[953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 01:28:42 volumiorpi3a volumio[953]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Jul 07 01:28:42 volumiorpi3a volumio[953]: code: 'auth/network-request-failed', Jul 07 01:28:42 volumiorpi3a volumio[953]: a: null Jul 07 01:28:42 volumiorpi3a volumio[953]: } Jul 07 01:28:42 volumiorpi3a volumio[953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 01:28:43 volumiorpi3a sudo[17610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-07 01:27 Jul 07 01:28:43 volumiorpi3a sudo[17610]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"