-- Logs begin at Sat 2026-08-29 16:21:34 CEST, end at Sat 2026-08-29 20:02:44 CEST. -- Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=debug msg="completed challenge" Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=debug msg="completed challenge" Aug 29 20:01:00 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:00+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:01 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetQueue Aug 29 20:01:01 coax-streamer volumio[3208]: info: CoreStateMachine::getQueue Aug 29 20:01:01 coax-streamer volumio[3208]: info: CorePlayQueue::getQueue Aug 29 20:01:01 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:01+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:01:01 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:01 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:01+02:00" level=debug msg="new websocket client" Aug 29 20:01:01 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:01 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:01+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:01 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:01+02:00" level=debug msg="completed challenge" Aug 29 20:01:02 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:02+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:02 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:02+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:01:02 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:02+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:02 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:02+02:00" level=debug msg="completed challenge" Aug 29 20:01:03 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:03+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:03 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:03+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:04 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:04+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:04 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:04+02:00" level=debug msg="completed challenge" Aug 29 20:01:04 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:04+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:04 coax-streamer go-librespot[23169]: time="2026-08-29T20:01:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:04 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:04 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:04 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:04 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:04 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:04 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:01:04 coax-streamer volumio[3208]: (node:3208) 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: 55609) Aug 29 20:01:04 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:04 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:04 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:05 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 29 20:01:05 coax-streamer volumio[3208]: info: In handleBrowseUri, curUri=spotify Aug 29 20:01:06 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:06 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:06 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:06 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:07 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:07 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:07 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:07 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 29 20:01:07 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:07 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:07 coax-streamer go-librespot[23248]: go-librespot daemon starting... Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=debug msg="app state loaded" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+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]" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=info msg="zeroconf server listening on port 39413" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=debug msg="obtained new client token: AAEZ+3ZasO8Tesulvw73XcW2y6syT/s0KJxfuxjSpDEHB1Kmq0Qc/1T6qKYNEXdjjXRfi/HmZDO7q/uwLByZhfBizS6r5f/eDqw2IHQADQVn3xPyfey9V3gM4Wrard15bGXNsoGvW+a1IX3uMyW14xSqc6f83QllzicWIr3rDxXdQ9ijJaTXzHkyIZjDF39U1+ZNI4plaXwG9AifHkL55RYpvT1/r+14J8bEuYMVAuz59UCaAgCGonrF" Aug 29 20:01:07 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=debug msg="completed challenge" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=debug msg="completed challenge" Aug 29 20:01:08 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:08+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:09 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:09+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:01:09 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:09+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:09 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:09+02:00" level=debug msg="completed challenge" Aug 29 20:01:09 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:09+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:10 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:10 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:10+02:00" level=debug msg="new websocket client" Aug 29 20:01:10 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:10 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:10+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:01:10 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:10+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:10 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:10+02:00" level=debug msg="completed challenge" Aug 29 20:01:10 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:10+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:11 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:11+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:11 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:11+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:11 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:11+02:00" level=debug msg="completed challenge" Aug 29 20:01:12 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:12+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:12 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:12 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:12+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:12 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:12+02:00" level=debug msg="completed challenge" Aug 29 20:01:12 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:12+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:12 coax-streamer go-librespot[23248]: time="2026-08-29T20:01:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:12 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:12 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:12 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:13 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:13 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:13 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:01:13 coax-streamer volumio[3208]: (node:3208) 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: 55610) Aug 29 20:01:13 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:13 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:13 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:15 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:15 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:15 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:15 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 29 20:01:15 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:15 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:15 coax-streamer go-librespot[23280]: go-librespot daemon starting... Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="app state loaded" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+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]" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=info msg="zeroconf server listening on port 44135" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="obtained new client token: AAEbkaUbL+fKSdhJ8BB/iiuHwo7g7eosiDLRDHbTLD7uRHAnTKmOoevqOp7kn7/nGHjI+/Oknc3my/eXMNBFCQjcv0cbi1OKCXIwRd7VWt4v6ZsW9UlP8lq6pFHbRrZaN5ag3Gv6uMFScYPLQHdyWMGgC10ctz5zYnzhhQVdnLT6xblEuR8sh4tKeisaDjtt31cQ6QUOigwprOoOYtvdkPdMVJnrZE8xWSJX8Yyu1eRr10WvrwC20i6f" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=debug msg="completed challenge" Aug 29 20:01:16 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:16+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=debug msg="completed challenge" Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=debug msg="completed challenge" Aug 29 20:01:17 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:17+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:18 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:18+02:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 29 20:01:18 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:18+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:18 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:18+02:00" level=debug msg="completed challenge" Aug 29 20:01:18 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:18 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:18+02:00" level=debug msg="new websocket client" Aug 29 20:01:18 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:18 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:18+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:19 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 29 20:01:19 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:19+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:19 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:19+02:00" level=debug msg="completed challenge" Aug 29 20:01:19 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:19+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:20 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:20+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:20 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:20+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:20 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:20+02:00" level=debug msg="completed challenge" Aug 29 20:01:20 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:20+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:20 coax-streamer go-librespot[23280]: time="2026-08-29T20:01:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:20 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:20 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:20 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:21 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:21 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:21 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:01:21 coax-streamer volumio[3208]: (node:3208) 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: 55611) Aug 29 20:01:21 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:21 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:21 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:23 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:23 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:23 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:23 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 29 20:01:23 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:23 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:23 coax-streamer go-librespot[23296]: go-librespot daemon starting... Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="app state loaded" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+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]" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=info msg="zeroconf server listening on port 33761" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="obtained new client token: AAH5BvAv+E3lwOyHd3rlkWGVj+8KlAoQAQOhXM+tyOBNm2zhTzCfhtw5tUnHT7NFm6+3WN5JcJ1jjTKgAunxWrqRrpZqhiG9izU5WE7xZj68/+rfDor1cFxU5rsPP6rw0ob/TfovaLvwaiKbmKwu5ayJwyIPeTDoP2Rzg1x4Gk6Sp8iTH6SUt5sVLQrRjoD+iYfHLosYB70cqXTStSTXTEdc5lDbyNUmh8ijvcyCwUgq5y/wBwRDOU+V" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=debug msg="completed challenge" Aug 29 20:01:24 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:24+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:25 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:25 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:25+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:25 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:25+02:00" level=debug msg="completed challenge" Aug 29 20:01:25 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:25+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:25 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:25+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Aug 29 20:01:25 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:25+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:01:26 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:26+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:26 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:26+02:00" level=debug msg="completed challenge" Aug 29 20:01:26 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:26+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:26 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:26 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:26+02:00" level=debug msg="new websocket client" Aug 29 20:01:26 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:27 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:27+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:01:27 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:27+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:27 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:27+02:00" level=debug msg="completed challenge" Aug 29 20:01:27 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:27+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:28 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:28+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:28 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:28+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:28 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:28+02:00" level=debug msg="completed challenge" Aug 29 20:01:28 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:28+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:29 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:29 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:29+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:29 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:29+02:00" level=debug msg="completed challenge" Aug 29 20:01:29 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:29+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:29 coax-streamer go-librespot[23296]: time="2026-08-29T20:01:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:29 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:29 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:29 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:29 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:29 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:29 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:01:29 coax-streamer volumio[3208]: (node:3208) 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: 55612) Aug 29 20:01:29 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:29 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:29 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:32 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:32 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:32 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:32 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 29 20:01:32 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:32 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:32 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 29 20:01:32 coax-streamer volumio[3208]: info: In handleBrowseUri, curUri=spotify/playlists Aug 29 20:01:32 coax-streamer go-librespot[23329]: go-librespot daemon starting... Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=debug msg="app state loaded" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+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]" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=info msg="zeroconf server listening on port 41661" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:32 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:32+02:00" level=debug msg="obtained new client token: AAFd9AhHn3eRp79aSvOTJtqy1mpF7TstBcE6A4QVoIMDJTKNg2U5GvcchIMgOYcDlcYJGhKDLt1Qe8DizbJ8rU5JPwzh1XLRIkWtWC2nwQQR3XNKPDygkHVdPYxj0XQ4mZTn7harEIu/xroYi+J4+KZvb8Qa4mYgpRsjD+n8ZJ0t5YBZIXJ6+pkWrRNqM2/786aUFRhZvJqb/zgUyIN16F5OnFEQCNWqJ6hrRUZu0NhmLE4iD4lQVcx2" Aug 29 20:01:32 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=debug msg="completed challenge" Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=debug msg="completed challenge" Aug 29 20:01:33 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:33+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:34 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:34 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:34+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:34 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:34+02:00" level=debug msg="completed challenge" Aug 29 20:01:34 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:34+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:35 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 29 20:01:35 coax-streamer volumio[3208]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:1tjtxm4b4cO3uYPWOQzXvz Aug 29 20:01:35 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:35+02:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 29 20:01:35 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:35+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:35 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:35+02:00" level=debug msg="completed challenge" Aug 29 20:01:35 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:35 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:35+02:00" level=debug msg="new websocket client" Aug 29 20:01:35 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:35 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:35+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:36 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:36+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 29 20:01:36 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:36+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:36 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:36+02:00" level=debug msg="completed challenge" Aug 29 20:01:36 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:36+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:36 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:36+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:73A2r5rBa1ke5MdCYZW0jK Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3MmtFohpZUmEzwUQgJvg0I Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5BRipbXVxysFmKUdu8tLZK Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6UfRY5YBWEhosTdE6PObsB Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3UbQeqtzZTt4CvqJ00RUjF Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:06X9KlAtIDGYnxtAchgwQS Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:7vOfZsVmL63yihfkPJyXlu Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1JviGGDsjSeDtquUxAknOd Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:7ois1WbkgFLBqMinZzHYrG Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0aOH62uqDzuvCvN2jdVtZs Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3K5OTM3xhD9aDBERn2VjX6 Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5Xq0JbQYoihey4UtHusnRK Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:71DoOi4NyoQht1Pso0r05O Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5riSr1LFW4Bv7JzMNwIc9C Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2MlQuvVsq23rnanz6OYaB5 Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3IMYGAepUFCJkExoOqrx2W Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6742DFLS3ImdjUgM6poonl Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6QPXvoyrH41zn9PGdaWCBc Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1uxXQMClOERzl1VVyPRCpV Aug 29 20:01:36 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1jrFQOfMFVYfL6u1V4cHbw Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5AZ9ufVqT9PqYQWybDePZJ Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6K3Cn17xE6tsuMeP2S8zmU Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5PdwFGbRYmHOZsce3lRmg8 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:4u4kBbeXn3mswdgllfzlfz Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0d5w1766AttEaJwgHeOdJg Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6kRRR9wBKn3lehdWQez8Hb Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:7rAYud7r0T46QFoK1hG1eU Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5onosMoIeq82BvOgpsZqaO Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:4pcLAkL1FX7QG01468LSxO Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3zpj9dvJABiyMrmLCPw6i8 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3H5J3ynlgbAErQTWBDMU2N Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:13mmcz1NtHhe99Q54QjnGg Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1H13WKDhOgwVeWzveBPTh0 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2dWodLagebVJMcisn6v3b9 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6PAyaH9TtZ0yGzfTm4U6GV Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3dnr2FigpqoQ7pNA4EKtp3 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1XVLE44WA7d5HnLHLC9dcf Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5UIq2zbapMbSU7Z2SEq8j0 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2zRdwxJioA8ID32oJAEngl Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3mKdw0slI6wmnb1FBrvwn2 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5CY039j1Mt4fGfBzfJqBNP Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:637xWjdmJY7CAQJsnsT7Fs Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0JjVhWJGDKHADulJ8EjVAL Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1LIpKS4BBcBZj6O4hR1IEB Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2k5cHiCcMnsVWQ61XARxCG Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0UsdtLayy7SqJ4rUsDMRk8 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0UuRIovHyU6KeTzY4gS0L2 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1IxyInl6wL3TWqctOKOuWR Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3h0AGkGzB952Cj8F5mRz9f Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6mlUCIOjUFLqlBAYbeGH5J Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5wfTQ5HrOate1FaaGMMBTS Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2Auvf9323aflLwtcZLeK6e Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3XiFWZoHQtGUYIdtShPwPD Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:19HAMJjxgNev75SjlJN997 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2rLQiI9vDgSPlqW12GwoPd Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:7jI7n0B2SvrzTcsJVuceCw Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2uoqOlmkCRwJTV9BEuGrRi Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5k1KFuEwm1qUpLuWlT2G5P Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0K2WjMLZYr09LKwurGRYRE Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6ZsKRsnPsdZvXfqqE6bTPy Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:7FXj7Qg3YorUxdrzvrcY25 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1r9KLSr0rvpm0e02x5z29L Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2408a07TNDga6lMlaIFLEU Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0Itq1iyUrxuCxca2c7j3lJ Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1opNhHzl7YYxo1szmZOLAA Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2wQiu7NvvWnsRLpPPTgygu Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1AM8QdDFZMq6SrrqUnuQ9P Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5j486QGLfZKV7AqrKqTk4z Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2fw1SuBu3JjreBJNHRBV7y Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5szXQmDipstzA9uLSDKLEx Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:6NmxczRtZvRdzBsvW6X50D Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0WM2HuVH92EzsckcwKwJ40 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:62RD5fRLYIGPD9JF8GxjRb Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2OCMSRQEBLQGp6QtniV3oq Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5P6DsA0BDK1EFnbIYkQJPE Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:7oQs0qakNPmmRNvXcr9QBT Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1UTEsHRi7KRbLUDjxsuBDL Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:2xykWpg9oo4uQ3Y5tUiuNu Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:3JdXkw3tciWHKam7ih3pMe Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:5SJFrcfI4P3zTNRoLQmeH7 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:0auFXH2fCXdSHwnrRoGIUH Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:01xOacs6t5ivxdqrzwZUof Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:34FT3XSrmqJN8LqRrlghlR Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:1qZOyqI6DLdU09YrP7G0y8 Aug 29 20:01:37 coax-streamer volumio[3208]: info: Preloading song: spotify:track:03sHfagupAASywtosEAetj Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:73A2r5rBa1ke5MdCYZW0jK in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3MmtFohpZUmEzwUQgJvg0I in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5BRipbXVxysFmKUdu8tLZK in service spop Aug 29 20:01:37 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:37+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:37 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:37+02:00" level=debug msg="completed challenge" Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6UfRY5YBWEhosTdE6PObsB in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3UbQeqtzZTt4CvqJ00RUjF in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:06X9KlAtIDGYnxtAchgwQS in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:7vOfZsVmL63yihfkPJyXlu in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1JviGGDsjSeDtquUxAknOd in service spop Aug 29 20:01:37 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:37+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:37 coax-streamer go-librespot[23329]: time="2026-08-29T20:01:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:37 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:37 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:37 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:7ois1WbkgFLBqMinZzHYrG in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0aOH62uqDzuvCvN2jdVtZs in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3K5OTM3xhD9aDBERn2VjX6 in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5Xq0JbQYoihey4UtHusnRK in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:71DoOi4NyoQht1Pso0r05O in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5riSr1LFW4Bv7JzMNwIc9C in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2MlQuvVsq23rnanz6OYaB5 in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3IMYGAepUFCJkExoOqrx2W in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6742DFLS3ImdjUgM6poonl in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6QPXvoyrH41zn9PGdaWCBc in service spop Aug 29 20:01:37 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1uxXQMClOERzl1VVyPRCpV in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1jrFQOfMFVYfL6u1V4cHbw in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5AZ9ufVqT9PqYQWybDePZJ in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6K3Cn17xE6tsuMeP2S8zmU in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5PdwFGbRYmHOZsce3lRmg8 in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:4u4kBbeXn3mswdgllfzlfz in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0d5w1766AttEaJwgHeOdJg in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:38 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:38 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:01:38 coax-streamer volumio[3208]: (node:3208) 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: 55613) Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6kRRR9wBKn3lehdWQez8Hb in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:38 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:38 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:7rAYud7r0T46QFoK1hG1eU in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5onosMoIeq82BvOgpsZqaO in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:4pcLAkL1FX7QG01468LSxO in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3zpj9dvJABiyMrmLCPw6i8 in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3H5J3ynlgbAErQTWBDMU2N in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:13mmcz1NtHhe99Q54QjnGg in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1H13WKDhOgwVeWzveBPTh0 in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2dWodLagebVJMcisn6v3b9 in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6PAyaH9TtZ0yGzfTm4U6GV in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3dnr2FigpqoQ7pNA4EKtp3 in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1XVLE44WA7d5HnLHLC9dcf in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5UIq2zbapMbSU7Z2SEq8j0 in service spop Aug 29 20:01:38 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2zRdwxJioA8ID32oJAEngl in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3mKdw0slI6wmnb1FBrvwn2 in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5CY039j1Mt4fGfBzfJqBNP in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:637xWjdmJY7CAQJsnsT7Fs in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0JjVhWJGDKHADulJ8EjVAL in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1LIpKS4BBcBZj6O4hR1IEB in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2k5cHiCcMnsVWQ61XARxCG in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0UsdtLayy7SqJ4rUsDMRk8 in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0UuRIovHyU6KeTzY4gS0L2 in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1IxyInl6wL3TWqctOKOuWR in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3h0AGkGzB952Cj8F5mRz9f in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6mlUCIOjUFLqlBAYbeGH5J in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5wfTQ5HrOate1FaaGMMBTS in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2Auvf9323aflLwtcZLeK6e in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3XiFWZoHQtGUYIdtShPwPD in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:19HAMJjxgNev75SjlJN997 in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2rLQiI9vDgSPlqW12GwoPd in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:7jI7n0B2SvrzTcsJVuceCw in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2uoqOlmkCRwJTV9BEuGrRi in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:01:39 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:01:39 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:39 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5k1KFuEwm1qUpLuWlT2G5P in service spop Aug 29 20:01:39 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0K2WjMLZYr09LKwurGRYRE in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6ZsKRsnPsdZvXfqqE6bTPy in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:7FXj7Qg3YorUxdrzvrcY25 in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1r9KLSr0rvpm0e02x5z29L in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2408a07TNDga6lMlaIFLEU in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0Itq1iyUrxuCxca2c7j3lJ in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1opNhHzl7YYxo1szmZOLAA in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2wQiu7NvvWnsRLpPPTgygu in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1AM8QdDFZMq6SrrqUnuQ9P in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5j486QGLfZKV7AqrKqTk4z in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:40 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2fw1SuBu3JjreBJNHRBV7y in service spop Aug 29 20:01:40 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:40 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 29 20:01:40 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:40 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:40 coax-streamer go-librespot[23355]: go-librespot daemon starting... Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5szXQmDipstzA9uLSDKLEx in service spop Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=debug msg="app state loaded" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:6NmxczRtZvRdzBsvW6X50D in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0WM2HuVH92EzsckcwKwJ40 in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:62RD5fRLYIGPD9JF8GxjRb in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2OCMSRQEBLQGp6QtniV3oq in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5P6DsA0BDK1EFnbIYkQJPE in service spop Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+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]" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=info msg="zeroconf server listening on port 42061" Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:7oQs0qakNPmmRNvXcr9QBT in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1UTEsHRi7KRbLUDjxsuBDL in service spop Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:2xykWpg9oo4uQ3Y5tUiuNu in service spop Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=debug msg="obtained new client token: AAG8yG5/80wrltrRfo5dwpejlTxostz2DQvoYZIC57ltw5d2Ow/Csscs/k0eyxANlnNwawIAdSU9p+9daMPsp8EEOsGOOtFs/KuVjkmAno4dXXozL3Pmbz2U0PCiW81pnbNLKgY9v8miVQ8JZygAkegq7yhO/bqjNMl02YaaCiCf6TWN4EKNiHKWf5GV/sIAtpO1l6DT0CRSNI0/9qCw4YXt4DDr4JrPc+iFcPDpaG2bnsjWZLOutxDd" Aug 29 20:01:40 coax-streamer volumio[3208]: info: Exploding uri spotify:track:3JdXkw3tciWHKam7ih3pMe in service spop Aug 29 20:01:40 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:41 coax-streamer volumio[3208]: info: Exploding uri spotify:track:5SJFrcfI4P3zTNRoLQmeH7 in service spop Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=debug msg="completed challenge" Aug 29 20:01:41 coax-streamer volumio[3208]: info: Exploding uri spotify:track:0auFXH2fCXdSHwnrRoGIUH in service spop Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:41 coax-streamer volumio[3208]: info: Exploding uri spotify:track:01xOacs6t5ivxdqrzwZUof in service spop Aug 29 20:01:41 coax-streamer volumio[3208]: info: Exploding uri spotify:track:34FT3XSrmqJN8LqRrlghlR in service spop Aug 29 20:01:41 coax-streamer volumio[3208]: info: Exploding uri spotify:track:1qZOyqI6DLdU09YrP7G0y8 in service spop Aug 29 20:01:41 coax-streamer volumio[3208]: info: Exploding uri spotify:track:03sHfagupAASywtosEAetj in service spop Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=debug msg="completed challenge" Aug 29 20:01:41 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:41+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:42 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:42 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:42+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:42 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:42+02:00" level=debug msg="completed challenge" Aug 29 20:01:42 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:42+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:43 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:43+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:01:43 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:43+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:43 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:43+02:00" level=debug msg="completed challenge" Aug 29 20:01:43 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::ClearQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::stop Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::clearPlayQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::addQueueItems Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::addQueueItems Aug 29 20:01:43 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:73A2r5rBa1ke5MdCYZW0jK Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:73A2r5rBa1ke5MdCYZW0jK Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::updateTrackBlock Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::getTrackBlock Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::play index 0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::addQueueItems Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::addQueueItems Aug 29 20:01:43 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3MmtFohpZUmEzwUQgJvg0I Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3MmtFohpZUmEzwUQgJvg0I Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5BRipbXVxysFmKUdu8tLZK Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5BRipbXVxysFmKUdu8tLZK Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6UfRY5YBWEhosTdE6PObsB Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6UfRY5YBWEhosTdE6PObsB Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3UbQeqtzZTt4CvqJ00RUjF Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3UbQeqtzZTt4CvqJ00RUjF Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:06X9KlAtIDGYnxtAchgwQS Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:06X9KlAtIDGYnxtAchgwQS Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7vOfZsVmL63yihfkPJyXlu Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7vOfZsVmL63yihfkPJyXlu Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1JviGGDsjSeDtquUxAknOd Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1JviGGDsjSeDtquUxAknOd Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7ois1WbkgFLBqMinZzHYrG Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7ois1WbkgFLBqMinZzHYrG Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0aOH62uqDzuvCvN2jdVtZs Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0aOH62uqDzuvCvN2jdVtZs Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3K5OTM3xhD9aDBERn2VjX6 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3K5OTM3xhD9aDBERn2VjX6 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5Xq0JbQYoihey4UtHusnRK Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5Xq0JbQYoihey4UtHusnRK Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:71DoOi4NyoQht1Pso0r05O Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:71DoOi4NyoQht1Pso0r05O Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5riSr1LFW4Bv7JzMNwIc9C Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5riSr1LFW4Bv7JzMNwIc9C Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2MlQuvVsq23rnanz6OYaB5 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2MlQuvVsq23rnanz6OYaB5 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3IMYGAepUFCJkExoOqrx2W Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3IMYGAepUFCJkExoOqrx2W Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6742DFLS3ImdjUgM6poonl Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6742DFLS3ImdjUgM6poonl Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6QPXvoyrH41zn9PGdaWCBc Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6QPXvoyrH41zn9PGdaWCBc Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1uxXQMClOERzl1VVyPRCpV Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1uxXQMClOERzl1VVyPRCpV Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1jrFQOfMFVYfL6u1V4cHbw Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1jrFQOfMFVYfL6u1V4cHbw Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5AZ9ufVqT9PqYQWybDePZJ Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5AZ9ufVqT9PqYQWybDePZJ Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6K3Cn17xE6tsuMeP2S8zmU Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6K3Cn17xE6tsuMeP2S8zmU Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5PdwFGbRYmHOZsce3lRmg8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5PdwFGbRYmHOZsce3lRmg8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:4u4kBbeXn3mswdgllfzlfz Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:4u4kBbeXn3mswdgllfzlfz Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0d5w1766AttEaJwgHeOdJg Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0d5w1766AttEaJwgHeOdJg Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6kRRR9wBKn3lehdWQez8Hb Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6kRRR9wBKn3lehdWQez8Hb Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7rAYud7r0T46QFoK1hG1eU Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7rAYud7r0T46QFoK1hG1eU Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5onosMoIeq82BvOgpsZqaO Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5onosMoIeq82BvOgpsZqaO Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:4pcLAkL1FX7QG01468LSxO Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:4pcLAkL1FX7QG01468LSxO Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3zpj9dvJABiyMrmLCPw6i8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3zpj9dvJABiyMrmLCPw6i8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3H5J3ynlgbAErQTWBDMU2N Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3H5J3ynlgbAErQTWBDMU2N Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:13mmcz1NtHhe99Q54QjnGg Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:13mmcz1NtHhe99Q54QjnGg Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1H13WKDhOgwVeWzveBPTh0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1H13WKDhOgwVeWzveBPTh0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2dWodLagebVJMcisn6v3b9 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2dWodLagebVJMcisn6v3b9 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6PAyaH9TtZ0yGzfTm4U6GV Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6PAyaH9TtZ0yGzfTm4U6GV Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3dnr2FigpqoQ7pNA4EKtp3 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3dnr2FigpqoQ7pNA4EKtp3 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1XVLE44WA7d5HnLHLC9dcf Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1XVLE44WA7d5HnLHLC9dcf Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5UIq2zbapMbSU7Z2SEq8j0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5UIq2zbapMbSU7Z2SEq8j0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2zRdwxJioA8ID32oJAEngl Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2zRdwxJioA8ID32oJAEngl Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3mKdw0slI6wmnb1FBrvwn2 Aug 29 20:01:43 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:43+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3mKdw0slI6wmnb1FBrvwn2 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5CY039j1Mt4fGfBzfJqBNP Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5CY039j1Mt4fGfBzfJqBNP Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:637xWjdmJY7CAQJsnsT7Fs Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:637xWjdmJY7CAQJsnsT7Fs Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0JjVhWJGDKHADulJ8EjVAL Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0JjVhWJGDKHADulJ8EjVAL Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1LIpKS4BBcBZj6O4hR1IEB Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1LIpKS4BBcBZj6O4hR1IEB Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2k5cHiCcMnsVWQ61XARxCG Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2k5cHiCcMnsVWQ61XARxCG Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0UsdtLayy7SqJ4rUsDMRk8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0UsdtLayy7SqJ4rUsDMRk8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0UuRIovHyU6KeTzY4gS0L2 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0UuRIovHyU6KeTzY4gS0L2 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1IxyInl6wL3TWqctOKOuWR Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1IxyInl6wL3TWqctOKOuWR Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3h0AGkGzB952Cj8F5mRz9f Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3h0AGkGzB952Cj8F5mRz9f Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6mlUCIOjUFLqlBAYbeGH5J Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6mlUCIOjUFLqlBAYbeGH5J Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5wfTQ5HrOate1FaaGMMBTS Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5wfTQ5HrOate1FaaGMMBTS Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2Auvf9323aflLwtcZLeK6e Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2Auvf9323aflLwtcZLeK6e Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3XiFWZoHQtGUYIdtShPwPD Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3XiFWZoHQtGUYIdtShPwPD Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:19HAMJjxgNev75SjlJN997 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:19HAMJjxgNev75SjlJN997 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2rLQiI9vDgSPlqW12GwoPd Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2rLQiI9vDgSPlqW12GwoPd Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7jI7n0B2SvrzTcsJVuceCw Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7jI7n0B2SvrzTcsJVuceCw Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2uoqOlmkCRwJTV9BEuGrRi Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2uoqOlmkCRwJTV9BEuGrRi Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5k1KFuEwm1qUpLuWlT2G5P Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5k1KFuEwm1qUpLuWlT2G5P Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0K2WjMLZYr09LKwurGRYRE Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0K2WjMLZYr09LKwurGRYRE Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6ZsKRsnPsdZvXfqqE6bTPy Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6ZsKRsnPsdZvXfqqE6bTPy Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1r9KLSr0rvpm0e02x5z29L Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1r9KLSr0rvpm0e02x5z29L Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2408a07TNDga6lMlaIFLEU Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2408a07TNDga6lMlaIFLEU Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0Itq1iyUrxuCxca2c7j3lJ Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0Itq1iyUrxuCxca2c7j3lJ Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1opNhHzl7YYxo1szmZOLAA Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1opNhHzl7YYxo1szmZOLAA Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2wQiu7NvvWnsRLpPPTgygu Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2wQiu7NvvWnsRLpPPTgygu Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1AM8QdDFZMq6SrrqUnuQ9P Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1AM8QdDFZMq6SrrqUnuQ9P Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5j486QGLfZKV7AqrKqTk4z Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5j486QGLfZKV7AqrKqTk4z Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2fw1SuBu3JjreBJNHRBV7y Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2fw1SuBu3JjreBJNHRBV7y Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5szXQmDipstzA9uLSDKLEx Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5szXQmDipstzA9uLSDKLEx Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6NmxczRtZvRdzBsvW6X50D Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6NmxczRtZvRdzBsvW6X50D Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0WM2HuVH92EzsckcwKwJ40 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0WM2HuVH92EzsckcwKwJ40 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:62RD5fRLYIGPD9JF8GxjRb Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:62RD5fRLYIGPD9JF8GxjRb Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2OCMSRQEBLQGp6QtniV3oq Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2OCMSRQEBLQGp6QtniV3oq Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5P6DsA0BDK1EFnbIYkQJPE Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5P6DsA0BDK1EFnbIYkQJPE Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7oQs0qakNPmmRNvXcr9QBT Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7oQs0qakNPmmRNvXcr9QBT Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1UTEsHRi7KRbLUDjxsuBDL Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1UTEsHRi7KRbLUDjxsuBDL Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2xykWpg9oo4uQ3Y5tUiuNu Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2xykWpg9oo4uQ3Y5tUiuNu Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3JdXkw3tciWHKam7ih3pMe Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3JdXkw3tciWHKam7ih3pMe Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5SJFrcfI4P3zTNRoLQmeH7 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5SJFrcfI4P3zTNRoLQmeH7 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0auFXH2fCXdSHwnrRoGIUH Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0auFXH2fCXdSHwnrRoGIUH Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:01xOacs6t5ivxdqrzwZUof Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:01xOacs6t5ivxdqrzwZUof Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:34FT3XSrmqJN8LqRrlghlR Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:34FT3XSrmqJN8LqRrlghlR Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1qZOyqI6DLdU09YrP7G0y8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1qZOyqI6DLdU09YrP7G0y8 Aug 29 20:01:43 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:03sHfagupAASywtosEAetj Aug 29 20:01:43 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:03sHfagupAASywtosEAetj Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::stop Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::updateTrackBlock Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::getTrackBlock Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:01:43 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:43 coax-streamer volumio[3208]: info: [1788026503497] ControllerSpotify::clearAddPlayTrack Aug 29 20:01:43 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:01:43 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:43 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:43+02:00" level=debug msg="new websocket client" Aug 29 20:01:43 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:44 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:44+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:01:44 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:44+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:44 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:44+02:00" level=debug msg="completed challenge" Aug 29 20:01:44 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:44+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:45 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:45+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:45 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:45+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:45 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:45+02:00" level=debug msg="completed challenge" Aug 29 20:01:45 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:45+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:45 coax-streamer go-librespot[23355]: time="2026-08-29T20:01:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:45 coax-streamer volumio[3208]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Aug 29 20:01:45 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:45 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:45 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:46 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:46 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:46 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:01:46 coax-streamer volumio[3208]: (node:3208) 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: 55614) Aug 29 20:01:46 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:46 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:46 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:48 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:48 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:48 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:48 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 29 20:01:48 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:48 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:48 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:01:48 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:01:48 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:48 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:48 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:01:48 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:48 coax-streamer volumio[3208]: info: [1788026508983] ControllerSpotify::clearAddPlayTrack Aug 29 20:01:48 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:01:48 coax-streamer volumio[3208]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:49 coax-streamer go-librespot[23389]: go-librespot daemon starting... Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="app state loaded" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+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]" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=info msg="zeroconf server listening on port 37645" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="obtained new client token: AAFQ+6flbNoBfuPtoLcpQRvH6HIj9B+vhCznXDvmsrKmPkaVomUzaiyN68SUlVqe9T9k0MT0ELr6TC2g4cj78mODKwaOzxRJpIS66N8bsVqWO99E3k0MVrvTW7/SuzyN6nzTJW+oZn1/UbIitmS6Zb3vutbO0GxwS/Q0V+wvWYU5ppVYgwdNa+pnPhUjvogvME4mA78TLntehudqQPYgzwoYCCW96MBK0AQ50zVVQ4V9fQBPVXzvbb63" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=debug msg="completed challenge" Aug 29 20:01:49 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:49+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=debug msg="completed challenge" Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=debug msg="completed challenge" Aug 29 20:01:50 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:50+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:51 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::ClearQueue Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::stop Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::clearPlayQueue Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::addQueueItems Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::addQueueItems Aug 29 20:01:51 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:51 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:user:spotify:playlist:1tjtxm4b4cO3uYPWOQzXvz Aug 29 20:01:51 coax-streamer volumio[3208]: info: Exploding uri spotify:user:spotify:playlist:1tjtxm4b4cO3uYPWOQzXvz in service spop Aug 29 20:01:51 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:51+02:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Aug 29 20:01:51 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:51+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Aug 29 20:01:51 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:51 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:51+02:00" level=debug msg="new websocket client" Aug 29 20:01:51 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::updateTrackBlock Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::getTrackBlock Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::play index 0 Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::stop Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:51 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:01:51 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:51 coax-streamer volumio[3208]: info: [1788026511824] ControllerSpotify::clearAddPlayTrack Aug 29 20:01:51 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:01:51 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:51+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:01:52 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:52+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:52 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:52+02:00" level=debug msg="completed challenge" Aug 29 20:01:52 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:52+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:53 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:53+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:53 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:53+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:53 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:53+02:00" level=debug msg="completed challenge" Aug 29 20:01:53 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:53+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:53 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:53+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:54 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:54+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:54 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:54+02:00" level=debug msg="completed challenge" Aug 29 20:01:54 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:01:54 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:01:54 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:54+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:54 coax-streamer go-librespot[23389]: time="2026-08-29T20:01:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:54 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:01:54 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:01:54 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: socket hang up Aug 29 20:01:54 coax-streamer volumio[3208]: at connResetException (internal/errors.js:639:14) Aug 29 20:01:54 coax-streamer volumio[3208]: at Socket.socketOnEnd (_http_client.js:499:23) Aug 29 20:01:54 coax-streamer volumio[3208]: at Socket.emit (events.js:412:35) Aug 29 20:01:54 coax-streamer volumio[3208]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 29 20:01:54 coax-streamer volumio[3208]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 29 20:01:54 coax-streamer volumio[3208]: (node:3208) 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: 55615) Aug 29 20:01:54 coax-streamer volumio[3208]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Aug 29 20:01:54 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:01:54 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:01:54 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:01:57 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:01:57 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:01:57 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:01:57 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 29 20:01:57 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:01:57 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:01:57 coax-streamer go-librespot[23420]: go-librespot daemon starting... Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="app state loaded" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="stored credentials not found" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+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]" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=info msg="zeroconf server listening on port 38077" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="obtained new client token: AAGxbbVxx49ys5B+HrBwsHVSzT1Vf6c5hkbGhAMstLTkk5ZR2Zq8BX/FLCqwKmfDbRpW6ONuXUL0jg9MnddwWws+zRubVNIKuPYlutBMujVQXi9cXVR+X9mEFTbOnd0Dpr7bGKk1C26Q47UYHy4ACLlZpRhdX+pBaXR7M3NrysJiJq+OATQQYhQs1Iq82GlS9j7TJhiPJt/Tkm3NcQaR874UTdPSGbP3rg3s5xrfroKnhuHsZ5WwJrC3" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=debug msg="completed challenge" Aug 29 20:01:58 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:58+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=debug msg="completed challenge" Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:01:59 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioplayNextItems Aug 29 20:01:59 coax-streamer volumio[3208]: info: CoreStateMachine::playNextItems Aug 29 20:01:59 coax-streamer volumio[3208]: info: CorePlayQueue::addPlayNextItems Aug 29 20:01:59 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:01:59 coax-streamer volumio[3208]: info: Adding Item to play next: spotify:user:spotify:playlist:1tjtxm4b4cO3uYPWOQzXvz Aug 29 20:01:59 coax-streamer volumio[3208]: info: Using cached record of: spotify:user:spotify:playlist:1tjtxm4b4cO3uYPWOQzXvz Aug 29 20:01:59 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:01:59 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:01:59 coax-streamer volumio[3208]: info: CoreStateMachine::updateTrackBlock Aug 29 20:01:59 coax-streamer volumio[3208]: info: CorePlayQueue::getTrackBlock Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=debug msg="completed keyexchange" Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=debug msg="completed challenge" Aug 29 20:01:59 coax-streamer go-librespot[23420]: time="2026-08-29T20:01:59+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:00 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:00+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:02:00 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:00+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:00 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:00+02:00" level=debug msg="completed challenge" Aug 29 20:02:00 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:00 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:00+02:00" level=debug msg="new websocket client" Aug 29 20:02:00 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:02:01 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:01+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:01 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:02:01 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:02:01 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:01 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:01 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:02:01 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:01 coax-streamer volumio[3208]: info: [1788026521379] ControllerSpotify::clearAddPlayTrack Aug 29 20:02:01 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:02:01 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 29 20:02:01 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:01+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:01 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:01+02:00" level=debug msg="completed challenge" Aug 29 20:02:01 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:01+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:02 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:02+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:02:02 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:02+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:02 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:02+02:00" level=debug msg="completed challenge" Aug 29 20:02:02 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:02+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:02 coax-streamer go-librespot[23420]: time="2026-08-29T20:02:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:02 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:02:02 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:02:03 coax-streamer volumio[3208]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Aug 29 20:02:03 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:02:03 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:02:03 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:03 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:02:03 coax-streamer volumio[3208]: (node:3208) 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: 55616) Aug 29 20:02:03 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:02:03 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:02:03 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:06 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:06 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:06 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:02:06 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 29 20:02:06 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:02:06 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:02:06 coax-streamer go-librespot[23494]: go-librespot daemon starting... Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="app state loaded" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="stored credentials not found" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+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]" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=info msg="zeroconf server listening on port 46237" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="obtained new client token: AAHr65AziQ066yLzQsBVkFsOVrLrhT72jSsmdoYb3Y15b95Tr7qL1coW8lZFtiE8zhQzKr5jVPTRBlgGm/k81Si4hEAXh2mPqCGBR1JqI3JgbyejEEfxuOxzqT2uOpm19hFbCcXUf2qTUN0kR9NUHylb3Ibuj1R2TL3MzLI87p279ScJ3N8/EK6ONjctrLty2zqqJcGcq2V5GDnAd9ed8d3/OJTPVulgcv6MnlOicQTTIDNZ/Gx5Xhzz" Aug 29 20:02:06 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 29 20:02:06 coax-streamer volumio[3208]: error: Could not enable audio output: Device not found or multiroom not enabled Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=debug msg="completed challenge" Aug 29 20:02:06 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:06+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:07 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:02:07 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:07+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:07 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:07+02:00" level=debug msg="completed challenge" Aug 29 20:02:07 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:07+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:08 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:02:08 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:08+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:08 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:08+02:00" level=debug msg="completed challenge" Aug 29 20:02:08 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:08+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:08 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:08+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:02:09 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:09 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:09+02:00" level=debug msg="new websocket client" Aug 29 20:02:09 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:02:09 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:09+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:09 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:09+02:00" level=debug msg="completed challenge" Aug 29 20:02:09 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:09+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:09 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:09+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:02:10 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:10+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:10 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:10+02:00" level=debug msg="completed challenge" Aug 29 20:02:10 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:10+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:10 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:10+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:02:11 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:11+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:11 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:11+02:00" level=debug msg="completed challenge" Aug 29 20:02:11 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:11+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:11 coax-streamer go-librespot[23494]: time="2026-08-29T20:02:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:11 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:02:11 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:02:11 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:02:12 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:02:12 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:12 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:02:12 coax-streamer volumio[3208]: (node:3208) 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: 55617) Aug 29 20:02:12 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:02:12 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:02:12 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:14 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:14 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:02:14 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 29 20:02:14 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:02:14 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:02:14 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:14 coax-streamer go-librespot[23513]: go-librespot daemon starting... Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=debug msg="app state loaded" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=debug msg="stored credentials not found" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+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]" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=info msg="zeroconf server listening on port 34787" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:02:14 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:14+02:00" level=debug msg="obtained new client token: AAGtEwokX9zHLovixdxDYk3HNJ2Hvu2BAzxd9MWO+9JycrpnDjRg+qTZtmurMyHP5a7xOn5u2TA80pTXikMqswpiv3wC+Zbaj2auRA7l/8q+Vr3KiDEeeCpqcMhhkTsp1Z+odO+gNjnJe3mKONi+HwWyrWPKpqdxfsbsGDN4kKkC/MDMo5M/laZAJy5uattd/PxPsCvXlXCYcQ3v2Co8JVO8rH2vcBZ725mV7IZQxBTqXF1gr1Jno1mB" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=debug msg="completed challenge" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=debug msg="completed challenge" Aug 29 20:02:15 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:15+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:16 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:02:16 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:16+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:16 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:16+02:00" level=debug msg="completed challenge" Aug 29 20:02:16 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:16+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:17 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:17+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:02:17 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:17+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:17 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:17+02:00" level=debug msg="completed challenge" Aug 29 20:02:17 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:17 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:17+02:00" level=debug msg="new websocket client" Aug 29 20:02:17 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:17+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:17 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:02:18 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:18+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:02:18 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:18+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:18 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:18+02:00" level=debug msg="completed challenge" Aug 29 20:02:18 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:18+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:19 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:19+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:02:19 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:19+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:19 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:19+02:00" level=debug msg="completed challenge" Aug 29 20:02:19 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:19+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:19 coax-streamer go-librespot[23513]: time="2026-08-29T20:02:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:19 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:02:19 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:02:19 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:02:20 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:02:20 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:20 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:02:20 coax-streamer volumio[3208]: (node:3208) 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: 55618) Aug 29 20:02:20 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:02:20 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:02:20 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:22 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:22 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:22 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:02:22 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 29 20:02:22 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:02:22 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:02:22 coax-streamer go-librespot[23547]: go-librespot daemon starting... Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="app state loaded" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="stored credentials not found" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+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]" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=info msg="zeroconf server listening on port 39857" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="obtained new client token: AAHl1+ZZ8ofFQ+ar5rZrHzroJNiViHPD4n17/2EhqJys2vW3rJBDITWjNqRyEGsUa6lMxjztXZupFEpf3EdHaufeBCzOtkNuLxTYZD3hyesqyB6Vvr6HbNQEg8/X16swR3tNO7xBpUCZPPD3bq9Vt1k8favC9Wyif0L0zJLoRDdtC0ipxcvys0/KD5blLABJXCrMKGBiGgSB/HaokdwEN4XiHGx3WnquVMgDp/tjUaIY5h+6YKON+Fpy" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=debug msg="completed challenge" Aug 29 20:02:23 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:23+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=debug msg="completed challenge" Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=debug msg="completed challenge" Aug 29 20:02:24 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:24+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:25 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:25+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:02:25 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:25 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:25+02:00" level=debug msg="new websocket client" Aug 29 20:02:25 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:02:25 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:25+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:25 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:25+02:00" level=debug msg="completed challenge" Aug 29 20:02:26 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:26+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:26 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:26+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:02:26 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:26+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:26 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:26+02:00" level=debug msg="completed challenge" Aug 29 20:02:26 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:26+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:27 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:27+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:02:27 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:27+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:27 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:27+02:00" level=debug msg="completed challenge" Aug 29 20:02:28 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:28+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:28 coax-streamer go-librespot[23547]: time="2026-08-29T20:02:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:28 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:02:28 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:02:28 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:02:28 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:02:28 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:28 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:02:28 coax-streamer volumio[3208]: (node:3208) 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: 55619) Aug 29 20:02:28 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:02:28 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:02:28 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.113Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.208Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=http://pushupdates.volumio.org duration=93.689866ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.300Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=186.031849ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.391Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://www.googleapis.com duration=275.130814ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.423Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://securetoken.googleapis.com duration=304.404563ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.472Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://google.com duration=358.549538ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.498Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=379.246156ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.509Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=395.654582ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.543Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=424.661747ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.559Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=http://cddb.volumio.org duration=444.102357ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.580Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://functions.volumio.cloud duration=462.012543ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.580Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://functions.volumio.cloud duration=461.502956ms Aug 29 20:02:30 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:02:30 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:02:30 coax-streamer volumio[3208]: info: Discovery: Getting this device information Aug 29 20:02:30 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:02:30 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:30 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:02:30 coax-streamer volumio[3208]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.85 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 29 20:02:30 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 20:02:30 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.717Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=http://plugins.volumio.org duration=601.271167ms Aug 29 20:02:30 coax-streamer volumio5-onboarding[3990]: time=2026-08-29T18:02:30.742Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.85:49912 @ 0x2588030" latency=66.699158ms timeout=10s endpoint=https://database.volumio.cloud duration=625.026469ms Aug 29 20:02:31 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:31 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:31 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:02:31 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Aug 29 20:02:31 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:02:31 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:02:31 coax-streamer go-librespot[23589]: go-librespot daemon starting... Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=debug msg="app state loaded" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=debug msg="stored credentials not found" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=info msg="zeroconf server listening on port 41363" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:02:31 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:31+02:00" level=debug msg="obtained new client token: AAFT5rpPaDfqKWihW2twu0PdAqb0rETOHQaL4WRf31X/2T7QvGe4/5jIAHOSZbqYgotlAlCHNfqbC/QrIFGZ2pJdtwyVXFnQlRmQETmw2VbTdJY61Tk21zcNR0B8wufYzgq+89n13KG5CbkxZVPX0s8Vv7JX0mQ6N9w8lkYyldc/0Te2gEgaC4htRI6eeYZ3B2VNu1Vsb2m+9e0Ey/bf/f0yJiT2yQvUoh5s7lPxctfycv1oo1jv7yYk" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=debug msg="completed challenge" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:32 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 29 20:02:32 coax-streamer volumio[3208]: error: Could not enable audio output: Device not found or multiroom not enabled Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=debug msg="completed challenge" Aug 29 20:02:32 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:32+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:33 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:02:33 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:33+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:33 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:33+02:00" level=debug msg="completed challenge" Aug 29 20:02:33 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:33+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:34 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:34+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Aug 29 20:02:34 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:34 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:34+02:00" level=debug msg="new websocket client" Aug 29 20:02:34 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:02:34 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:34+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:34 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:34+02:00" level=debug msg="completed challenge" Aug 29 20:02:34 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:34+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:35 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:35+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 29 20:02:35 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:35+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:35 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:35+02:00" level=debug msg="completed challenge" Aug 29 20:02:35 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:35+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:35 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:35+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Aug 29 20:02:36 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:36+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:36 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:36+02:00" level=debug msg="completed challenge" Aug 29 20:02:36 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:36+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:36 coax-streamer go-librespot[23589]: time="2026-08-29T20:02:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:36 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:02:36 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:02:36 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket closed Aug 29 20:02:37 coax-streamer volumio[3208]: info: Getting Spotify volume Aug 29 20:02:37 coax-streamer volumio[3208]: (node:3208) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:37 coax-streamer volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Aug 29 20:02:37 coax-streamer volumio[3208]: (node:3208) 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: 55620) Aug 29 20:02:37 coax-streamer volumio[3208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 20:02:37 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioGetState Aug 29 20:02:37 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:38 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:02:38 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:02:38 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:38 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:38 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:02:38 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:38 coax-streamer volumio[3208]: info: [1788026558161] ControllerSpotify::clearAddPlayTrack Aug 29 20:02:38 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:02:38 coax-streamer volumio[3208]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:39 coax-streamer volumio[3208]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 20:02:39 coax-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 29 20:02:39 coax-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Aug 29 20:02:39 coax-streamer systemd[1]: Stopped go-librespot Daemon. Aug 29 20:02:39 coax-streamer systemd[1]: Started go-librespot Daemon. Aug 29 20:02:39 coax-streamer go-librespot[23624]: go-librespot daemon starting... Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="app state loaded" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="stored credentials not found" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 20:02:39 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::ClearQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::stop Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::clearPlayQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::addQueueItems Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::addQueueItems Aug 29 20:02:39 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:73A2r5rBa1ke5MdCYZW0jK Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:73A2r5rBa1ke5MdCYZW0jK Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::updateTrackBlock Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::getTrackBlock Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::play index 0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::addQueueItems Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::addQueueItems Aug 29 20:02:39 coax-streamer volumio[3208]: info: Preload queue cleared Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3MmtFohpZUmEzwUQgJvg0I Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3MmtFohpZUmEzwUQgJvg0I Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5BRipbXVxysFmKUdu8tLZK Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5BRipbXVxysFmKUdu8tLZK Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6UfRY5YBWEhosTdE6PObsB Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6UfRY5YBWEhosTdE6PObsB Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3UbQeqtzZTt4CvqJ00RUjF Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3UbQeqtzZTt4CvqJ00RUjF Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:06X9KlAtIDGYnxtAchgwQS Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:06X9KlAtIDGYnxtAchgwQS Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7vOfZsVmL63yihfkPJyXlu Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7vOfZsVmL63yihfkPJyXlu Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1JviGGDsjSeDtquUxAknOd Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1JviGGDsjSeDtquUxAknOd Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7ois1WbkgFLBqMinZzHYrG Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7ois1WbkgFLBqMinZzHYrG Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0aOH62uqDzuvCvN2jdVtZs Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0aOH62uqDzuvCvN2jdVtZs Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3K5OTM3xhD9aDBERn2VjX6 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3K5OTM3xhD9aDBERn2VjX6 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5Xq0JbQYoihey4UtHusnRK Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5Xq0JbQYoihey4UtHusnRK Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:71DoOi4NyoQht1Pso0r05O Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:71DoOi4NyoQht1Pso0r05O Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5riSr1LFW4Bv7JzMNwIc9C Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5riSr1LFW4Bv7JzMNwIc9C Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2MlQuvVsq23rnanz6OYaB5 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2MlQuvVsq23rnanz6OYaB5 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3IMYGAepUFCJkExoOqrx2W Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3IMYGAepUFCJkExoOqrx2W Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6742DFLS3ImdjUgM6poonl Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6742DFLS3ImdjUgM6poonl Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6QPXvoyrH41zn9PGdaWCBc Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6QPXvoyrH41zn9PGdaWCBc Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1uxXQMClOERzl1VVyPRCpV Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1uxXQMClOERzl1VVyPRCpV Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1jrFQOfMFVYfL6u1V4cHbw Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1jrFQOfMFVYfL6u1V4cHbw Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5AZ9ufVqT9PqYQWybDePZJ Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5AZ9ufVqT9PqYQWybDePZJ Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6K3Cn17xE6tsuMeP2S8zmU Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6K3Cn17xE6tsuMeP2S8zmU Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5PdwFGbRYmHOZsce3lRmg8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5PdwFGbRYmHOZsce3lRmg8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:4u4kBbeXn3mswdgllfzlfz Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:4u4kBbeXn3mswdgllfzlfz Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0d5w1766AttEaJwgHeOdJg Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0d5w1766AttEaJwgHeOdJg Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6kRRR9wBKn3lehdWQez8Hb Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6kRRR9wBKn3lehdWQez8Hb Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7rAYud7r0T46QFoK1hG1eU Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7rAYud7r0T46QFoK1hG1eU Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5onosMoIeq82BvOgpsZqaO Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5onosMoIeq82BvOgpsZqaO Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:4pcLAkL1FX7QG01468LSxO Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:4pcLAkL1FX7QG01468LSxO Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3zpj9dvJABiyMrmLCPw6i8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3zpj9dvJABiyMrmLCPw6i8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3H5J3ynlgbAErQTWBDMU2N Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3H5J3ynlgbAErQTWBDMU2N Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:13mmcz1NtHhe99Q54QjnGg Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:13mmcz1NtHhe99Q54QjnGg Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1H13WKDhOgwVeWzveBPTh0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1H13WKDhOgwVeWzveBPTh0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2dWodLagebVJMcisn6v3b9 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2dWodLagebVJMcisn6v3b9 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6PAyaH9TtZ0yGzfTm4U6GV Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6PAyaH9TtZ0yGzfTm4U6GV Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3dnr2FigpqoQ7pNA4EKtp3 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3dnr2FigpqoQ7pNA4EKtp3 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1XVLE44WA7d5HnLHLC9dcf Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1XVLE44WA7d5HnLHLC9dcf Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5UIq2zbapMbSU7Z2SEq8j0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5UIq2zbapMbSU7Z2SEq8j0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2zRdwxJioA8ID32oJAEngl Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2zRdwxJioA8ID32oJAEngl Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3mKdw0slI6wmnb1FBrvwn2 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3mKdw0slI6wmnb1FBrvwn2 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5CY039j1Mt4fGfBzfJqBNP Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5CY039j1Mt4fGfBzfJqBNP Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:637xWjdmJY7CAQJsnsT7Fs Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:637xWjdmJY7CAQJsnsT7Fs Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0JjVhWJGDKHADulJ8EjVAL Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0JjVhWJGDKHADulJ8EjVAL Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1LIpKS4BBcBZj6O4hR1IEB Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1LIpKS4BBcBZj6O4hR1IEB Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2k5cHiCcMnsVWQ61XARxCG Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2k5cHiCcMnsVWQ61XARxCG Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0UsdtLayy7SqJ4rUsDMRk8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0UsdtLayy7SqJ4rUsDMRk8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0UuRIovHyU6KeTzY4gS0L2 Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02: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]" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0UuRIovHyU6KeTzY4gS0L2 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1IxyInl6wL3TWqctOKOuWR Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1IxyInl6wL3TWqctOKOuWR Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3h0AGkGzB952Cj8F5mRz9f Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3h0AGkGzB952Cj8F5mRz9f Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6mlUCIOjUFLqlBAYbeGH5J Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6mlUCIOjUFLqlBAYbeGH5J Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5wfTQ5HrOate1FaaGMMBTS Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5wfTQ5HrOate1FaaGMMBTS Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2Auvf9323aflLwtcZLeK6e Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2Auvf9323aflLwtcZLeK6e Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3XiFWZoHQtGUYIdtShPwPD Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3XiFWZoHQtGUYIdtShPwPD Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:19HAMJjxgNev75SjlJN997 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:19HAMJjxgNev75SjlJN997 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2rLQiI9vDgSPlqW12GwoPd Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2rLQiI9vDgSPlqW12GwoPd Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7jI7n0B2SvrzTcsJVuceCw Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7jI7n0B2SvrzTcsJVuceCw Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2uoqOlmkCRwJTV9BEuGrRi Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2uoqOlmkCRwJTV9BEuGrRi Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5k1KFuEwm1qUpLuWlT2G5P Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=info msg="zeroconf server listening on port 36749" Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5k1KFuEwm1qUpLuWlT2G5P Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0K2WjMLZYr09LKwurGRYRE Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0K2WjMLZYr09LKwurGRYRE Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6ZsKRsnPsdZvXfqqE6bTPy Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6ZsKRsnPsdZvXfqqE6bTPy Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1r9KLSr0rvpm0e02x5z29L Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1r9KLSr0rvpm0e02x5z29L Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2408a07TNDga6lMlaIFLEU Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2408a07TNDga6lMlaIFLEU Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0Itq1iyUrxuCxca2c7j3lJ Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0Itq1iyUrxuCxca2c7j3lJ Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1opNhHzl7YYxo1szmZOLAA Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1opNhHzl7YYxo1szmZOLAA Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2wQiu7NvvWnsRLpPPTgygu Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2wQiu7NvvWnsRLpPPTgygu Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1AM8QdDFZMq6SrrqUnuQ9P Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1AM8QdDFZMq6SrrqUnuQ9P Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5j486QGLfZKV7AqrKqTk4z Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5j486QGLfZKV7AqrKqTk4z Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2fw1SuBu3JjreBJNHRBV7y Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2fw1SuBu3JjreBJNHRBV7y Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5szXQmDipstzA9uLSDKLEx Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5szXQmDipstzA9uLSDKLEx Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:6NmxczRtZvRdzBsvW6X50D Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:6NmxczRtZvRdzBsvW6X50D Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0WM2HuVH92EzsckcwKwJ40 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0WM2HuVH92EzsckcwKwJ40 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:62RD5fRLYIGPD9JF8GxjRb Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:62RD5fRLYIGPD9JF8GxjRb Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2OCMSRQEBLQGp6QtniV3oq Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2OCMSRQEBLQGp6QtniV3oq Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5P6DsA0BDK1EFnbIYkQJPE Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5P6DsA0BDK1EFnbIYkQJPE Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:7oQs0qakNPmmRNvXcr9QBT Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:7oQs0qakNPmmRNvXcr9QBT Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1UTEsHRi7KRbLUDjxsuBDL Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1UTEsHRi7KRbLUDjxsuBDL Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:2xykWpg9oo4uQ3Y5tUiuNu Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:2xykWpg9oo4uQ3Y5tUiuNu Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:3JdXkw3tciWHKam7ih3pMe Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:3JdXkw3tciWHKam7ih3pMe Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:5SJFrcfI4P3zTNRoLQmeH7 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:5SJFrcfI4P3zTNRoLQmeH7 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:0auFXH2fCXdSHwnrRoGIUH Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:0auFXH2fCXdSHwnrRoGIUH Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:01xOacs6t5ivxdqrzwZUof Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:01xOacs6t5ivxdqrzwZUof Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:34FT3XSrmqJN8LqRrlghlR Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:34FT3XSrmqJN8LqRrlghlR Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:1qZOyqI6DLdU09YrP7G0y8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:1qZOyqI6DLdU09YrP7G0y8 Aug 29 20:02:39 coax-streamer volumio[3208]: info: Adding Item to queue: spotify:track:03sHfagupAASywtosEAetj Aug 29 20:02:39 coax-streamer volumio[3208]: info: Using cached record of: spotify:track:03sHfagupAASywtosEAetj Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::stop Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::saveQueue Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::updateTrackBlock Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::getTrackBlock Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:02:39 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:39 coax-streamer volumio[3208]: info: [1788026559621] ControllerSpotify::clearAddPlayTrack Aug 29 20:02:39 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="obtained new client token: AAEN1U/870k3m1VnLr8nWq4ic/lQ1zAZIGugGyHF5mio+dd2Xz64ooxIP7L8G/O2cUgW+PXYMHKRxef2EMw2qipOPI/kFSto1aclmF5/3qENQYbZ8HZVx3kNUE+W/MG60oW4mAufjereIEf9Ujbq1yBdWckIhbyRFbeLXdPKDWT0FeGjFjwhoUiF9geoccqXGDE8SBFMJdHQzFWZejjGZVqUciZdrO5PZurYqQhmR2HNBEp1h0yigF4Z" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=debug msg="completed challenge" Aug 29 20:02:39 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:39+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:40 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 29 20:02:40 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:40+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:40 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:40+02:00" level=debug msg="completed challenge" Aug 29 20:02:40 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:40+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:40 coax-streamer volumio[3208]: info: CoreCommandRouter::volumioPlay Aug 29 20:02:40 coax-streamer volumio[3208]: info: CoreStateMachine::play index undefined Aug 29 20:02:40 coax-streamer volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:02:40 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:40 coax-streamer volumio[3208]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:02:40 coax-streamer volumio[3208]: info: CorePlayQueue::getTrack 0 Aug 29 20:02:40 coax-streamer volumio[3208]: info: [1788026560658] ControllerSpotify::clearAddPlayTrack Aug 29 20:02:40 coax-streamer volumio[3208]: info: Sending Spotify command with payload to local API: /player/play Aug 29 20:02:41 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Aug 29 20:02:41 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:41+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:41 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:41+02:00" level=debug msg="completed challenge" Aug 29 20:02:41 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:41+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:41 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:41+02:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=debug msg="completed challenge" Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:42 coax-streamer volumio[3208]: info: Initializing connection to go-librespot Websocket Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=debug msg="new websocket client" Aug 29 20:02:42 coax-streamer volumio[3208]: info: Connection to go-librespot Websocket established Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=debug msg="completed challenge" Aug 29 20:02:42 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:42+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:42 coax-streamer volumio[3208]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Aug 29 20:02:42 coax-streamer volumio[3208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:02:42 coax-streamer volumio[3208]: TypeError: Cannot read property 'then' of undefined Aug 29 20:02:42 coax-streamer volumio[3208]: at outputs.disableAudioOutput (/volumio/app/plugins/audio_interface/outputs/index.js:273:9) Aug 29 20:02:42 coax-streamer volumio[3208]: at CoreCommandRouter.disableAudioOutput (/volumio/app/index.js:2257:30) Aug 29 20:02:42 coax-streamer volumio[3208]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1455:26) Aug 29 20:02:42 coax-streamer volumio[3208]: at Socket.emit (events.js:400:28) Aug 29 20:02:42 coax-streamer volumio[3208]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 20:02:42 coax-streamer volumio[3208]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 29 20:02:42 coax-streamer volumio[3208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:02:43 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:43+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Aug 29 20:02:43 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:43+02:00" level=debug msg="completed keyexchange" Aug 29 20:02:43 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:43+02:00" level=debug msg="completed challenge" Aug 29 20:02:44 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:44+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:44 coax-streamer go-librespot[23624]: time="2026-08-29T20:02:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Aug 29 20:02:44 coax-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:02:44 coax-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 20:02:44 coax-streamer sudo[23656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 20:01 Aug 29 20:02:44 coax-streamer sudo[23656]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"