-- Logs begin at Sun 2025-04-27 13:24:46 BST, end at Sun 2025-04-27 14:08:06 BST. -- Apr 27 14:07:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:02 volumio volumio[1133]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 14:07:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 14:07:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5339. Apr 27 14:07:02 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 14:07:02 volumio systemd[1]: Started go-librespot Daemon. Apr 27 14:07:02 volumio go-librespot[22390]: Librespot-go daemon starting... Apr 27 14:07:02 volumio go-librespot[22390]: time="2025-04-27T14:07:02+01:00" level=info msg="generated new device id: 5f8032d3e77ac1a8513d9b1eeaeb679d79c8c529" Apr 27 14:07:02 volumio go-librespot[22390]: time="2025-04-27T14:07:02+01:00" level=debug msg="stored credentials found for 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:05 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:05 volumio go-librespot[22390]: time="2025-04-27T14:07:05+01:00" level=debug msg="new websocket client" Apr 27 14:07:05 volumio volumio[1133]: info: Connection to go-librespot Websocket established Apr 27 14:07:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:07 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: info: Getting Spotify volume Apr 27 14:07:08 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:08 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:08 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:09 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:09 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:09 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:09 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:09 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:09 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:09 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:10 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:10 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:11 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:12 volumio go-librespot[22390]: time="2025-04-27T14:07:12+01: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" Apr 27 14:07:12 volumio volumio[1133]: (node:1133) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 27 14:07:12 volumio volumio[1133]: at connResetException (internal/errors.js:607:14) Apr 27 14:07:12 volumio volumio[1133]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 27 14:07:12 volumio volumio[1133]: at Socket.emit (events.js:327:22) Apr 27 14:07:12 volumio volumio[1133]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 14:07:12 volumio volumio[1133]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 14:07:12 volumio volumio[1133]: (node:1133) 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: 5599) Apr 27 14:07:12 volumio volumio[1133]: info: Connection to go-librespot Websocket closed Apr 27 14:07:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 14:07:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 14:07:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:12 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:13 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:14 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:14 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:15 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:15 volumio volumio[1133]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 14:07:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 14:07:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5340. Apr 27 14:07:15 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 14:07:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:15 volumio systemd[1]: Started go-librespot Daemon. Apr 27 14:07:15 volumio go-librespot[22415]: Librespot-go daemon starting... Apr 27 14:07:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:15 volumio go-librespot[22415]: time="2025-04-27T14:07:15+01:00" level=info msg="generated new device id: a490ea36bb564aa6a115d53b5032f06b65101b1e" Apr 27 14:07:15 volumio go-librespot[22415]: time="2025-04-27T14:07:15+01:00" level=debug msg="stored credentials found for 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:07:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:15 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:15 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:16 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:16 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:17 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:17 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:18 volumio go-librespot[22415]: time="2025-04-27T14:07:18+01:00" level=debug msg="new websocket client" Apr 27 14:07:18 volumio volumio[1133]: info: Connection to go-librespot Websocket established Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:18 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:18 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:19 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:19 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:20 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: info: Getting Spotify volume Apr 27 14:07:21 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:21 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:21 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:22 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:22 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:23 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:23 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:23 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:23 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:23 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:23 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:23 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:25 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:25 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:25 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:25 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:25 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:25 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:25 volumio go-librespot[22415]: time="2025-04-27T14:07:25+01: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" Apr 27 14:07:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 14:07:25 volumio volumio[1133]: (node:1133) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 27 14:07:25 volumio volumio[1133]: at connResetException (internal/errors.js:607:14) Apr 27 14:07:25 volumio volumio[1133]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 27 14:07:25 volumio volumio[1133]: at Socket.emit (events.js:327:22) Apr 27 14:07:25 volumio volumio[1133]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 14:07:25 volumio volumio[1133]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 14:07:25 volumio volumio[1133]: (node:1133) 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: 5600) Apr 27 14:07:25 volumio volumio[1133]: info: Connection to go-librespot Websocket closed Apr 27 14:07:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:27 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:27 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:27 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:27 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:27 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:27 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:27 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:28 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:28 volumio volumio[1133]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 14:07:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 14:07:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5341. Apr 27 14:07:28 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 14:07:29 volumio systemd[1]: Started go-librespot Daemon. Apr 27 14:07:29 volumio go-librespot[22456]: Librespot-go daemon starting... Apr 27 14:07:29 volumio go-librespot[22456]: time="2025-04-27T14:07:29+01:00" level=info msg="generated new device id: 63933c71e3c5a2429a817e52bf5e051fa6bdc1cc" Apr 27 14:07:29 volumio go-librespot[22456]: time="2025-04-27T14:07:29+01:00" level=debug msg="stored credentials found for 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:07:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:30 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:30 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:31 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:31 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:31 volumio go-librespot[22456]: time="2025-04-27T14:07:31+01:00" level=debug msg="new websocket client" Apr 27 14:07:31 volumio volumio[1133]: info: Connection to go-librespot Websocket established Apr 27 14:07:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:32 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:32 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:33 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:33 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:33 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:33 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:33 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:33 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:33 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:34 volumio volumio[1133]: info: Getting Spotify volume Apr 27 14:07:34 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 27 14:07:34 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:34 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:35 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:35 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:36 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:36 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:37 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:37 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:37 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:37 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:37 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:37 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:37 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:38 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:38 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio go-librespot[22456]: time="2025-04-27T14:07:39+01: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" Apr 27 14:07:39 volumio volumio[1133]: (node:1133) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 27 14:07:39 volumio volumio[1133]: at connResetException (internal/errors.js:607:14) Apr 27 14:07:39 volumio volumio[1133]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 27 14:07:39 volumio volumio[1133]: at Socket.emit (events.js:327:22) Apr 27 14:07:39 volumio volumio[1133]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 14:07:39 volumio volumio[1133]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 14:07:39 volumio volumio[1133]: (node:1133) 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: 5602) Apr 27 14:07:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 14:07:39 volumio volumio[1133]: info: Connection to go-librespot Websocket closed Apr 27 14:07:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:39 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:40 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:40 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:40 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:40 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:40 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:40 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:40 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:41 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:42 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:42 volumio volumio[1133]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 14:07:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 14:07:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5342. Apr 27 14:07:42 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 14:07:42 volumio systemd[1]: Started go-librespot Daemon. Apr 27 14:07:42 volumio go-librespot[22484]: Librespot-go daemon starting... Apr 27 14:07:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:42 volumio go-librespot[22484]: time="2025-04-27T14:07:42+01:00" level=info msg="generated new device id: f920c6406f478615d6ab0c9c894930a2b26235f7" Apr 27 14:07:42 volumio go-librespot[22484]: time="2025-04-27T14:07:42+01:00" level=debug msg="stored credentials found for 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:07:42 volumio ntpd[839]: Soliciting pool server 90.155.12.14 Apr 27 14:07:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:42 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio ntpd[839]: Soliciting pool server 132.226.210.133 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:43 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:43 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:44 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:44 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:44 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:44 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:44 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:44 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:44 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:45 volumio volumio[1133]: info: Initializing connection to go-librespot Websocket Apr 27 14:07:45 volumio go-librespot[22484]: time="2025-04-27T14:07:45+01:00" level=debug msg="new websocket client" Apr 27 14:07:45 volumio volumio[1133]: info: Connection to go-librespot Websocket established Apr 27 14:07:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:45 volumio go-librespot[22484]: time="2025-04-27T14:07:45+01:00" level=debug msg="obtained new client token: AABHUmOX3V/3xfBEjgWg3DoSGLYonMU+JjrrrUx62vCbX9L+xS5W5saMc8sq7gaVtBw42dUTS9Ry5b4tcI8oAGMbS355QaVaT78fwlfLawkmxA7gGe2e7iE/UcWRdWsMxrbmllc+R6Vp8y/vv9RCe2YkE+UQgvKUoMy1URWUbnMbseEUwdF/BKjI4x3e47Ntpiv/+++u6uyD1AmSpp6MNEnC5peEHgJf0fdUOYLoyMKWp97XP2+b9/Tnc3vhExo=" Apr 27 14:07:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:46 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:46 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:46 volumio go-librespot[22484]: time="2025-04-27T14:07:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 27 14:07:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:46 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:46 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:46 volumio ntpd[839]: Soliciting pool server 131.111.8.63 Apr 27 14:07:46 volumio go-librespot[22484]: time="2025-04-27T14:07:46+01:00" level=debug msg="completed keyexchange" Apr 27 14:07:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:46 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:46 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:46 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:47 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:47 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:47 volumio go-librespot[22484]: time="2025-04-27T14:07:47+01:00" level=debug msg="completed challenge" Apr 27 14:07:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:47 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:47 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:47 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:47 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:47 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: Getting Spotify volume Apr 27 14:07:48 volumio volumio[1133]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:48 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:48 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:49 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:49 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:49 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:49 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:49 volumio go-librespot[22484]: time="2025-04-27T14:07:49+01:00" level=debug msg="authenticated as 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:07:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:49 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:49 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:49 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:50 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:50 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:50 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:50 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:50 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:50 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:50 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:51 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:52 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:52 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:53 volumio go-librespot[22484]: time="2025-04-27T14:07:53+01:00" level=debug msg="authenticated as 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:07:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:53 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:53 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:53 volumio go-librespot[22484]: time="2025-04-27T14:07:53+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 27 14:07:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:53 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:53 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:53 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:53 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:53 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:54 volumio go-librespot[22484]: time="2025-04-27T14:07:54+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 27 14:07:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:54 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:54 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:54 volumio ntpd[839]: 195.171.43.10 local addr 192.168.1.117 -> Apr 27 14:07:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:54 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:54 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:54 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:54 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:54 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:55 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:55 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:55 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:55 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:55 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:55 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:55 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:56 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:56 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:57 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:57 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:57 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:57 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:57 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:57 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:57 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:58 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:58 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:59 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:59 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:59 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:59 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:59 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:07:59 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:07:59 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:00 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:00 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:01 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:01 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=debug msg="dealer connection opened" Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 27 14:08:02 volumio volumio[1133]: info: Spotify volume: 100 Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=debug msg="received connection id: M2EwNzIwNzItYTU1Ni00Y2Q2LTljYmYtYTY3Mzg4NWY2Y2YwK2RlYWxlcit0Y3A6Ly8wYWNhNDEwMS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRjM3QzgyQTk5RUI2MzYyNjdCNzI0ODBENTk1NTJEQ0U1Q0U1MUVEREE3QUM2RDY5QjUwMTUwNDczQjQzMkRFQw==" Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=error msg="failed receiving packet" error="failed reading packet payload: read tcp 192.168.1.117:55852->104.199.65.9:4070: read: connection reset by peer" Apr 27 14:08:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=debug msg="completed keyexchange" Apr 27 14:08:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:02 volumio go-librespot[22484]: time="2025-04-27T14:08:02+01:00" level=debug msg="put connect state because NEW_DEVICE" Apr 27 14:08:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:03 volumio go-librespot[22484]: time="2025-04-27T14:08:03+01:00" level=debug msg="completed challenge" Apr 27 14:08:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:03 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:03 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:04 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:04 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:05 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:05 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:06 volumio go-librespot[22484]: time="2025-04-27T14:08:06+01:00" level=debug msg="authenticated as 31c7vdw3exbjy2zzmpfyghma2ilu" Apr 27 14:08:06 volumio go-librespot[22484]: time="2025-04-27T14:08:06+01:00" level=debug msg="re-established accesspoint connection" Apr 27 14:08:06 volumio go-librespot[22484]: time="2025-04-27T14:08:06+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 27 14:08:06 volumio go-librespot[22484]: time="2025-04-27T14:08:06+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 27 14:08:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:06 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState Apr 27 14:08:06 volumio volumio[1133]: info: CorePlayQueue::getTrack 0 Apr 27 14:08:06 volumio volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 14:08:06 volumio volumio[1133]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Apr 27 14:08:06 volumio volumio[1133]: code: 'auth/network-request-failed', Apr 27 14:08:06 volumio volumio[1133]: a: null Apr 27 14:08:06 volumio volumio[1133]: } Apr 27 14:08:06 volumio volumio[1133]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 14:08:06 volumio sudo[22592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-27 14:07 Apr 27 14:08:06 volumio sudo[22592]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"