-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2026-08-28 14:36:54 CEST. -- Aug 28 14:35:00 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:00 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Aug 28 14:35:00 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:00 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:00 volumio-tw go-librespot[19254]: go-librespot daemon starting... Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=debug msg="app state loaded" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+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 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+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 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+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 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=info msg="zeroconf server listening on port 37641" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=debug msg="obtained new client token: AAGbbTzpvcwVf9cMavhBJ9AaTCOy9wIREQXoS2LQ1/MJBtUDM/3z7j9LqpUbuX1vIVIdTYHbXPXET09MuBXdHiE8Zv1WJfXK92phw6xjBOWUxPQcqc4n9bLLdICcyKLhkhA3jeB+JELC7og822Gb8qnUXtKVtdresZYvwwyBKkg2wLRlSj8e2ohVTTAZvuaOoAYfM3WpcPHIh5a+xwq8uGXv8gFJLLpJATKAK86mMGdb4fBkZsidGwoh" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=debug msg="completed challenge" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:00 volumio-tw go-librespot[19254]: time="2026-08-28T14:35:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:00 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:00 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:00 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:00 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:03 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:04 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:04 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:04 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Aug 28 14:35:04 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:04 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:04 volumio-tw go-librespot[19279]: go-librespot daemon starting... Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=debug msg="app state loaded" Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+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 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+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 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+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 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=info msg="zeroconf server listening on port 44771" Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=debug msg="obtained new client token: AAEc7uN4eo1IYMXM9FDjDSRjbFxPiSlSGCD1g4xMeMOyCx8rH6SdkZUAn5HE+F6cNJVONoMSVUcYmD9ZzksB6fcTVRomxedZkf/RbingVnSUAILjax3bd0UHbQ/xwnQrgi17xu9Z+cVxIIItLhGSCcfSfweBtX+iIs4qVv9/9B50gU8q6VGRRPkn9XdcwlEVE8QJlNMm9dcMJjn/NAy3eSExGa1GzzMJl2jmSeFqlkyK1eRvP03nWuu+" Aug 28 14:35:04 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:05 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:05+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:05 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:05+02:00" level=debug msg="completed challenge" Aug 28 14:35:05 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:05+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:05 volumio-tw go-librespot[19279]: time="2026-08-28T14:35:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:05 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:05 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:07 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:07 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:08 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:08 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Aug 28 14:35:08 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:08 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:08 volumio-tw go-librespot[19289]: go-librespot daemon starting... Aug 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+02:00" level=debug msg="app state loaded" Aug 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+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 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+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 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+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 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+02:00" level=info msg="zeroconf server listening on port 36867" Aug 28 14:35:08 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:08+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:09 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:09+02:00" level=debug msg="obtained new client token: AAHh4XU5jyKev3Fxh9kFfdODQPXNMZ5HLpTDVZw1vhYIRfuxclzhOq588eYGWZMNFXd1tKB7nHbd1WPD7TW2yDs7N0flwjVH8rNFrDvm8LWbe2yGIMU+EKTp1kg7Z59SRUvNmtc4wDEig+BjDWcvGEVvl4RrfIGj04TLQ2YSHHDvzASqDBqxsiy0ExI+31tuR11lj+TP53U9Ux48GyQ6d+dauZDEjZNh+H1nCG9TCyZgBX/2URfoGg==" Aug 28 14:35:09 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:09 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:09+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:09 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:09+02:00" level=debug msg="completed challenge" Aug 28 14:35:09 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:09+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:09 volumio-tw go-librespot[19289]: time="2026-08-28T14:35:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:09 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:09 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:10 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:10 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:12 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:12 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Aug 28 14:35:12 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:12 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:12 volumio-tw go-librespot[19313]: go-librespot daemon starting... Aug 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+02:00" level=debug msg="app state loaded" Aug 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+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 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+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 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+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 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+02:00" level=info msg="zeroconf server listening on port 40061" Aug 28 14:35:12 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:12+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:13 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=debug msg="new websocket client" Aug 28 14:35:13 volumio-tw volumio[817]: info: Connection to go-librespot Websocket established Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=debug msg="obtained new client token: AAGQRYt8jlbm0yG9f4ADxHIlfxqrWFXC2FrQZ7Y43BuFFx1z7K3/3HepYvs+pqu2vc9FwIajfCx3Bj09wCLAn6a6dBExOVRyai31B2Nw33hUBZ5hYHwunAmzIQP4Yoyy5ZekM0DLfc5KeVnhCsTYjXcvvcz7jUQQINSy7Bq9jMXuQyjKMGdW/xjkEKYR8X1oSz1psf/MS7BWLxUbzay8S3WwNM9GEwV4XA7TSGj4/2sGZ+VYglo0Xg==" Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=debug msg="completed challenge" Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:13 volumio-tw go-librespot[19313]: time="2026-08-28T14:35:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:13 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:13 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:13 volumio-tw volumio[817]: info: Connection to go-librespot Websocket closed Aug 28 14:35:16 volumio-tw volumio[817]: info: Getting Spotify volume Aug 28 14:35:16 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:16 volumio-tw volumio[817]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 28 14:35:16 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Aug 28 14:35:16 volumio-tw volumio[817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 28 14:35:16 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:35:16 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:35:16 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:16 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:16 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:16 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Aug 28 14:35:16 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:16 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:16 volumio-tw go-librespot[19324]: go-librespot daemon starting... Aug 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+02:00" level=debug msg="app state loaded" Aug 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+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 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+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 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+02:00" level=info msg="zeroconf server listening on port 38605" Aug 28 14:35:16 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:16+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:17 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:17+02:00" level=debug msg="obtained new client token: AAE5zoo/Cvfi5+0gLzr1QGFkQXxjFc0mXPWQZa7rNjjo0a8hnWU1zvwHd2YmxWzCTS8G9MaWLJlFzFwDfqe54R+zqKm1K9tiQixHaZ80wxwz9a1vxmZM8RfW2WDhVt4LBQYiIcqssG3rgIaMunFfrynnWsg92p8pM+FEajejfC/cIJbLa6cm6QoGf6LjbeS7iWI9WYaD7IJ3fJEV9uteo2jVnO5W7GKfpquDiW1WI5Pfmj4xeyl4tQ==" Aug 28 14:35:17 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:17 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:17+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:17 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:17+02:00" level=debug msg="completed challenge" Aug 28 14:35:17 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:17+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:17 volumio-tw go-librespot[19324]: time="2026-08-28T14:35:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:17 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:17 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:19 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:19 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:20 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:20 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Aug 28 14:35:20 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:20 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:20 volumio-tw go-librespot[19342]: go-librespot daemon starting... Aug 28 14:35:20 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:20 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:20+02:00" level=debug msg="app state loaded" Aug 28 14:35:20 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+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 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+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 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+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 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+02:00" level=info msg="zeroconf server listening on port 44641" Aug 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+02:00" level=debug msg="obtained new client token: AAGlz+JbkOXFZ3iv7m7zz+i64GkLymX/GNfPKFWrpbxOgz9epfd4Nz5l9TrlaqY4LtjEoQG3DeoDT+wmm0rUkLkUr+JaYj06MsXclIuxNMVfjt+VHGEdvU5VXsOACW/jf57/clWauTN5qiwuvrHqcBLim20sFWw+EPz9UUNGELxZR/EfMmb3+oRsmr93Qek6tu+2XCuXd8OXGV4BgwXIQsgxaiHNEt5nFjgxVQ4UuMi29doFDSCR4w==" Aug 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:21 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:21+02:00" level=debug msg="completed challenge" Aug 28 14:35:22 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:22+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:22 volumio-tw go-librespot[19342]: time="2026-08-28T14:35:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:22 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:22 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:22 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:22 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:25 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:25 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Aug 28 14:35:25 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:25 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:25 volumio-tw go-librespot[19397]: go-librespot daemon starting... Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=debug msg="app state loaded" Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:25 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:25 volumio-tw volumio[817]: info: Connection to go-librespot Websocket established Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=debug msg="new websocket client" Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+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 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+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 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+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 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=info msg="zeroconf server listening on port 34085" Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:25 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:25+02:00" level=debug msg="obtained new client token: AAECylIX3a6Z9iHoPIJwV5mlVwPn7z0xEkXM0Rg048jR/9IS102JvI21nU/6T723v6CgEjfEOAHSgzQTJXzXiO5kIHSVCASX6myXQj0cYqNgoE39kjWnps2ptL74hyJlJYMdgWc+sBLGe0x3loesPeQw7pXEFFe9VJDLzFNKT3HxixdVqrPXOAT7KIaoB8xle2NCTQiCxoJrQlZlVHwWhXfH7L2puzYxTMUJHgqDLkQP89x41f2asqzo" Aug 28 14:35:26 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:26+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 28 14:35:26 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:26+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 28 14:35:28 volumio-tw volumio[817]: info: Getting Spotify volume Aug 28 14:35:28 volumio-tw volumio[817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 28 14:35:28 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:35:28 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:35:30 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:30+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:30 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:30+02:00" level=debug msg="completed challenge" Aug 28 14:35:33 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:33+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:33 volumio-tw go-librespot[19397]: time="2026-08-28T14:35:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:33 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:33 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:33 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Error: socket hang up Aug 28 14:35:33 volumio-tw volumio[817]: at connResetException (internal/errors.js:607:14) Aug 28 14:35:33 volumio-tw volumio[817]: at Socket.socketOnEnd (_http_client.js:493:23) Aug 28 14:35:33 volumio-tw volumio[817]: at Socket.emit (events.js:327:22) Aug 28 14:35:33 volumio-tw volumio[817]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 28 14:35:33 volumio-tw volumio[817]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 28 14:35:33 volumio-tw volumio[817]: (node:817) 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: 9) Aug 28 14:35:33 volumio-tw volumio[817]: info: Connection to go-librespot Websocket closed Aug 28 14:35:36 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:36 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:36 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:36 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Aug 28 14:35:36 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:36 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:36 volumio-tw go-librespot[19421]: go-librespot daemon starting... Aug 28 14:35:36 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:36+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:36 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:36+02:00" level=debug msg="app state loaded" Aug 28 14:35:36 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+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 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+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 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+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 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=info msg="zeroconf server listening on port 40735" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=debug msg="obtained new client token: AAEFsHdG587y3+DIpkvyp80jEu5AOR9IZ5rq+Kgg2WUdswmL0UU1Ha4VeGcAwrvmhKRvCG3ybSJX1x7mSqAqiOP2dQ5IAEfwfEPXGudsb3U6kpXmWnEAN6Abo5thPwH8IpMa9ln0LEaxB68cMcVpvk6ROP4fjr23Rnz+6EN3UBY8oppy7dx9dnuvjk/ZP5YelmPOO8vIgLwWSOm+18EvqzQnAKO/yjoLUaoQI3iY6S4sIE5Ax6wZXC5z" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=debug msg="completed challenge" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:37 volumio-tw go-librespot[19421]: time="2026-08-28T14:35:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:37 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:37 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:39 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:39 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:40 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:40 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Aug 28 14:35:40 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:40 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:40 volumio-tw go-librespot[19446]: go-librespot daemon starting... Aug 28 14:35:40 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:40+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:40 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:40+02:00" level=debug msg="app state loaded" Aug 28 14:35:40 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+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 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+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 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+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 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=info msg="zeroconf server listening on port 39687" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=debug msg="obtained new client token: AAHGEtzREEyzQ0duwZZCNnVZ+DY1Dv+WaN12CC9kJquAiDTA9rswCjEfegW5AlpZ0KzRomYe/EkuvA00Ojn3dSu8Q9okqqu7F8WYeFjPuWtKR+6+86vqxdlfUw5lzjTC9LfUpyQnFJ89j+UetjfQMcdvFeiWWTBdMEKsgnFzDMaZO2O41uDlQ1fIe2vYtaKBknswyeKu0/ZkoZqVaGTD7x/1xDMfRwWQaOseGjsHT8LxZlIillEH01n9" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=debug msg="completed challenge" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:41 volumio-tw go-librespot[19446]: time="2026-08-28T14:35:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:41 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:41 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:42 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:42 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:44 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:44 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Aug 28 14:35:44 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:44 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:44 volumio-tw go-librespot[19456]: go-librespot daemon starting... Aug 28 14:35:44 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:44+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:44 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:44+02:00" level=debug msg="app state loaded" Aug 28 14:35:44 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+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 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+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 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+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 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=info msg="zeroconf server listening on port 35887" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=debug msg="obtained new client token: AAHJx0WO0I6BlRas3WvufwGa7d52l7ngtKxJHU/GSDUuXffv705MYNo6q2vHSK1PSyVVj8IP8yIf5fr8AOanWZFxMyYm3Erp9Aw4WMCvI6h8f/DWwdZtFZKur8/Pht7zxFS21IkqtpU8kqMY+UBip9AFE729tUHzJvZRjZlWoimohqYodCQD9UWRakAP1nEv+Yi1z61HKrdbSPitr+VZorb/QNuvIQKPE2BiYZ8rUIEy7KT6TG0W6Inv" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=debug msg="completed challenge" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:45 volumio-tw go-librespot[19456]: time="2026-08-28T14:35:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:45 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:45 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:45 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:45 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:48 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:48 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:48 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:48 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Aug 28 14:35:48 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:48 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:48 volumio-tw go-librespot[19466]: go-librespot daemon starting... Aug 28 14:35:48 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:48+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:48 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:48+02:00" level=debug msg="app state loaded" Aug 28 14:35:48 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+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 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+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 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=info msg="zeroconf server listening on port 39809" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=debug msg="obtained new client token: AAFCLm8yLXe1vITSLqFDdO6hSWYHptQys5KIip4SIxo/bu0/T6Xv7QG8+lpULD+eXUsxrED+wkuaqw+vXdK9bx3AsVn9vvM1WIFZcjQLB+QxLLVHRnDS+55uKCtJrhOZuQPEmfIjBwTGN2td9S7swgTQ46JDNKw5nVcdqmWM49/TnusTtGlsbcvQBXlFTp1wK3aY2dHI7ksgryYbetY80OErxBu67cRTKMOzazFgJrHRIHcd/4emv2mr" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=debug msg="completed challenge" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:49 volumio-tw go-librespot[19466]: time="2026-08-28T14:35:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:49 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:49 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:51 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:51 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:52 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:52 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Aug 28 14:35:52 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:52 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:52 volumio-tw go-librespot[19491]: go-librespot daemon starting... Aug 28 14:35:52 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:52+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:52 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:52+02:00" level=debug msg="app state loaded" Aug 28 14:35:52 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+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 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+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 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+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 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=info msg="zeroconf server listening on port 45839" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=debug msg="obtained new client token: AAGcgFBIxgrf+qHM2U25wjYUsdKqmiBP3QOb3DdETudHAwshrmTQN6rYSKgplSlPvxKezh6eVCBbv1vjqhgpxbeW5gdX9+x4eGDPViXM+7FaFD2TeuZ63m7POURoUGcxvMWam4KE2cfFiWBE0F4KnLm+DxcdcAW0VkXK3nk1numA/PGhhXsRi4RgkbmecfjuKNihpzFGzPA1pM3vaJg89MpMFEEEtxEonfyUwnslvOFswd0saBSRoqV+" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=debug msg="completed challenge" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:53 volumio-tw go-librespot[19491]: time="2026-08-28T14:35:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:53 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:53 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:54 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:54 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:35:57 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:35:57 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Aug 28 14:35:57 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:35:57 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:35:57 volumio-tw go-librespot[19501]: go-librespot daemon starting... Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=debug msg="app state loaded" Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+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 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+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 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+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 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=info msg="zeroconf server listening on port 38269" Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=debug msg="obtained new client token: AAFkc3qM9L0jLDCG/ffv2LtC7mmNREUcYuZ6wRBoNnPbnhbfYFS1fvYvfV/txF+yZKleh5RR0K/2XsVH4tp/oMp54wiReR3A8+84rsZ86dVfStFgFO5kC4ZByl4lDTmUc+tPUiB/YPw7M/ss/f4k3ZNQEAZJLJLcyVRyDYbc5m0+Cyx98k8Pcib4x9p7+Y+AA1Zd/RC9LTjzlVuGcG5N4okTocPF1G/vFJ94iK8sxViBCPVnQO5ylQgl" Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:35:57 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:35:57 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:57+02:00" level=debug msg="new websocket client" Aug 28 14:35:57 volumio-tw volumio[817]: info: Connection to go-librespot Websocket established Aug 28 14:35:58 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:58+02:00" level=debug msg="completed keyexchange" Aug 28 14:35:58 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:58+02:00" level=debug msg="completed challenge" Aug 28 14:35:58 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:58+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:35:58 volumio-tw go-librespot[19501]: time="2026-08-28T14:35:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:35:58 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:35:58 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:35:58 volumio-tw volumio[817]: info: Connection to go-librespot Websocket closed Aug 28 14:36:00 volumio-tw volumio[817]: info: Getting Spotify volume Aug 28 14:36:00 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:00 volumio-tw volumio[817]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 28 14:36:00 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 10) Aug 28 14:36:00 volumio-tw volumio[817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 28 14:36:00 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:00 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:01 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:01 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:01 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:01 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Aug 28 14:36:01 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:01 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:01 volumio-tw go-librespot[19525]: go-librespot daemon starting... Aug 28 14:36:01 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:01+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:01 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:01+02:00" level=debug msg="app state loaded" Aug 28 14:36:01 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+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 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+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 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+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 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=info msg="zeroconf server listening on port 36445" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=debug msg="obtained new client token: AAHElcfnYEy5sqBBvt0da1C0rPJCGr4u4heX4IMdIT62x8Efo10S2x5uQfhlDhe9X0gya7/9JjJPHjZ7bI9iYgMgMdBy2+AIeF0+ln11Twa5qw6XmwJ8X+ywWXGIOUBKqzxogywc63X3coQmO1X/QOFgh5bTGrQLI6IxxLF8HtbpcwpYmrlWXuv3LF+LrpIn5bAqgaxIfoQQx/Rpqh8u+5PWRH1oZOfClmGVsXk+CElwRJZjJoh1ZF4g" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=debug msg="completed challenge" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:02 volumio-tw go-librespot[19525]: time="2026-08-28T14:36:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:02 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:02 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:04 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:04 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:05 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:05 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Aug 28 14:36:05 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:05 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:05 volumio-tw go-librespot[19535]: go-librespot daemon starting... Aug 28 14:36:05 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:05+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:05 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:05+02:00" level=debug msg="app state loaded" Aug 28 14:36:05 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36: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 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36: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 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36: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 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=info msg="zeroconf server listening on port 44995" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=debug msg="obtained new client token: AAEZsAkzvQHZTMzd6kNa6P9QmuwHajoMAzSNN+Xbnv7pkqE4q/KsnubP5LbBSsBgvEKc8gP0IaKCFH6UhMuiui/KuFMgCYlHFb2Q8z1urDvcj3K6Ef5GLICEIW9teFkrjVoh4CNpWwdbtuWJsMxmbutYUSt8grZiukjh0SSuUBQAUomEl+Sod/dJ19l+ung92QOJ/iFvdvvBd9XgRg3cezCiZ27S1Ow6fPHcIaLYkY+0WpgF4LtmEfLE" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=debug msg="completed challenge" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:06 volumio-tw go-librespot[19535]: time="2026-08-28T14:36:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:06 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:06 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:07 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:07 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:09 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:09 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Aug 28 14:36:09 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:09 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:09 volumio-tw go-librespot[19545]: go-librespot daemon starting... Aug 28 14:36:09 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:09+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:09 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:09+02:00" level=debug msg="app state loaded" Aug 28 14:36:09 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+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 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+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 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+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 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=info msg="zeroconf server listening on port 45437" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=debug msg="obtained new client token: AAGOSIvtMcn6e515EMQp+c225dhpIszGg/zmGPg+OG+wB9qPY1J3CLZ17qa/aBROWZHRL2jG5gJf2c92WXCHnN4cyfoSeS9MBeW5wUFS5mE7Inx8AmeDqJ7Y/0kg1FGnZGnVdO91RG2wS9bu7Pu55hEGLq4d5FPNAkpjMjczvaopaphmb8tkii+3CWxq7sk4U5ba+8WMbdfXUgUHF7WmBpAuRNwECIr/hzHma/vyYoxhvNzpv+kHCwXk" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=debug msg="completed challenge" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:10 volumio-tw go-librespot[19545]: time="2026-08-28T14:36:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:10 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:10 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:10 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:10 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:13 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:13 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Aug 28 14:36:13 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:13 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:13 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:13 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:13 volumio-tw go-librespot[19569]: go-librespot daemon starting... Aug 28 14:36:13 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:13+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:13 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:13+02:00" level=debug msg="app state loaded" Aug 28 14:36:13 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36: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 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36: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 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36: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 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:14+02:00" level=info msg="zeroconf server listening on port 44049" Aug 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:14+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:14+02:00" level=debug msg="obtained new client token: AAF2Da6ae56y8RXNC+Q2YJpG5vrpXnVbMrDRLbmpakB2dXcgC/D6fGH5DDkt6SnenSQ73BL5iwXXbYPXJ5dNPAgNrDjJRkgbgWD+qUF3RCmUi+fR82cGyTeeURSESCJWGab8YYTmYpDf1rXOEf0VpkuCCFkshYkv6bSwe1UTPxWTZ/MJ8YWqUaE4+2kCM9M6UyJwTdknRci4fimxzmHVmWz//Pfn3jcfpZXMm4nZPiQ3bK/bMPo+8IgT" Aug 28 14:36:14 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:16 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:16 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:16+02:00" level=debug msg="new websocket client" Aug 28 14:36:16 volumio-tw volumio[817]: info: Connection to go-librespot Websocket established Aug 28 14:36:19 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:19+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:19 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:19+02:00" level=debug msg="completed challenge" Aug 28 14:36:19 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:19+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:19 volumio-tw volumio[817]: info: Getting Spotify volume Aug 28 14:36:19 volumio-tw go-librespot[19569]: time="2026-08-28T14:36:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:19 volumio-tw volumio[817]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 28 14:36:19 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:19 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:19 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Error: socket hang up Aug 28 14:36:19 volumio-tw volumio[817]: at connResetException (internal/errors.js:607:14) Aug 28 14:36:19 volumio-tw volumio[817]: at Socket.socketOnEnd (_http_client.js:493:23) Aug 28 14:36:19 volumio-tw volumio[817]: at Socket.emit (events.js:327:22) Aug 28 14:36:19 volumio-tw volumio[817]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 28 14:36:19 volumio-tw volumio[817]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 28 14:36:19 volumio-tw volumio[817]: (node:817) 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: 11) Aug 28 14:36:19 volumio-tw volumio[817]: info: Connection to go-librespot Websocket closed Aug 28 14:36:19 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:19 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:22 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:22 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:23 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:23 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Aug 28 14:36:23 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:23 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:23 volumio-tw go-librespot[19594]: go-librespot daemon starting... Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=debug msg="app state loaded" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+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 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+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 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=info msg="zeroconf server listening on port 34239" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=debug msg="obtained new client token: AAEolePVNM9avHobE4rPICA5V8SvGr3WlKTmPFUAC2xGZZ1j8QSRMwdXJvznkrwEwgjz/YTc9D3bsAHIieU5/EL+KlCpyev240CshOrV6tJnIxJAAJyahEeSLW0cB4uMUHTRZj5KSgRnHx4P4oLZzU5704x9XRgEovVaRAI8u7ad9vTq00Fv+u9BpTmBzsMgG4qyKnVqRxUIe0tOEaiyOpsGGAYfbwd3tJkjnBLpYunzOolxqww0LMBm" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=debug msg="completed challenge" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:23 volumio-tw go-librespot[19594]: time="2026-08-28T14:36:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:23 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:23 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:25 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:25 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:27 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:27 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Aug 28 14:36:27 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:27 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:27 volumio-tw go-librespot[19642]: go-librespot daemon starting... Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=debug msg="app state loaded" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+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 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+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 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+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 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=info msg="zeroconf server listening on port 43167" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=debug msg="obtained new client token: AAFABySTEm+o2DZBdJAthfIDRT8Vj6l372aVupqIC3LSOthVatsiqfm/Q9GJMgVItebJtrNdjUD8bylWMIReH5+a6NWHSfZXKA/GpCXU7g1qcY04Xb9hr6paGP5DhhFYeWvjN4qV7qUr/lJOOjk0BWwdjRVFUErg098DnRHsm5DcHHq/MQgvfUNCrxh5tpnFgB1v2N3wkOhi4RlO5MCeGHiYONGcPk/A5xYV4ReRyDCipn7/E8gNJdQf" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=debug msg="completed challenge" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:27 volumio-tw go-librespot[19642]: time="2026-08-28T14:36:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:27 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:27 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:28 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:28 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:31 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:31 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Aug 28 14:36:31 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:31 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:31 volumio-tw go-librespot[19667]: go-librespot daemon starting... Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=debug msg="app state loaded" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+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 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+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 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=info msg="zeroconf server listening on port 34435" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=debug msg="obtained new client token: AAGxcA1EPsKYuOPQ52E1rFTh2rO/+XICRz26a3zJ1ANY540YJk8+QmtYZX8cXc3k09nRJ+M9C+Azk3N3QvbxoQGTR2lsHzqlTNhbSfjh9Jw0RDu6D0qnRExluNBnxiYmsUFqAmD0S2mrsIVkPVsU3BQfGfza3bDDuAzEBaYYo7uEzqJfnt8piKlA8h3WHM+ihsY+S9akrvFHHJO6GhFhdOWxglSFjGgW6J7PNw+j6DgpOTs9lo/c6jg/" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=debug msg="completed challenge" Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:31 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=debug msg="new websocket client" Aug 28 14:36:31 volumio-tw volumio[817]: info: Connection to go-librespot Websocket established Aug 28 14:36:31 volumio-tw go-librespot[19667]: time="2026-08-28T14:36:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:31 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:31 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:31 volumio-tw volumio[817]: info: Connection to go-librespot Websocket closed Aug 28 14:36:34 volumio-tw sudo[19678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 14:36:34 volumio-tw sudo[19678]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 14:36:34 volumio-tw sudo[19681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 14:36:34 volumio-tw sudo[19681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 14:36:34 volumio-tw sudo[19678]: pam_unix(sudo:session): session closed for user root Aug 28 14:36:34 volumio-tw sudo[19681]: pam_unix(sudo:session): session closed for user root Aug 28 14:36:34 volumio-tw volumio[817]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 28 14:36:34 volumio-tw sudo[19686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 14:36:34 volumio-tw sudo[19686]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 14:36:34 volumio-tw sudo[19689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 14:36:34 volumio-tw sudo[19689]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 14:36:34 volumio-tw sudo[19686]: pam_unix(sudo:session): session closed for user root Aug 28 14:36:34 volumio-tw sudo[19689]: pam_unix(sudo:session): session closed for user root Aug 28 14:36:34 volumio-tw volumio[817]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: polling Total Clients: 9 Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:34 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 14:36:34 volumio-tw volumio[817]: info: Received Get System Info Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:36:34 volumio-tw volumio[817]: info: Discovery: Getting this device information Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:34 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:34 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:34 volumio-tw volumio[817]: info: Listing playlists Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:36:34 volumio-tw volumio[817]: info: FusionDsp - no preset used Aug 28 14:36:34 volumio-tw volumio[817]: info: Getting Spotify volume Aug 28 14:36:34 volumio-tw volumio[817]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Aug 28 14:36:34 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 14:36:34 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:34 volumio-tw volumio[817]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 28 14:36:34 volumio-tw volumio[817]: (node:817) 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: 12) Aug 28 14:36:34 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:34 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:34 volumio-tw volumio[817]: 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 28 14:36:35 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:35 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:35 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:35 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Aug 28 14:36:35 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:35 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:35 volumio-tw go-librespot[19693]: go-librespot daemon starting... Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=debug msg="app state loaded" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+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 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+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 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+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 28 14:36:35 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:35 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=info msg="zeroconf server listening on port 37827" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=debug msg="obtained new client token: AAEtB4CGsF4DlOfdaUf9gMiPoBsuMkx/LVCsQMgwp61u5c1PsHEMlgkZoSsMqgoDQ7by1LU2ahXw4oY/O7DSfKjda7o0s+QROh5tCrN1I6Ue9W0YRVcq77uyV9poHaOF9wY2A8WCb/1c/gjXuM47GAC+CsTkaulSWYIQvo05u3g0UWtiA6n11rP6TaWfsVKWaE9nvfMi+XJygMi69mpNaKRTr4r8xTiI6v5alOsvMEyjwypKNAMdpm5Y" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=debug msg="completed challenge" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:35 volumio-tw go-librespot[19693]: time="2026-08-28T14:36:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:35 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:35 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:36 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 28 14:36:36 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:36:36 volumio-tw volumio[817]: info: Received Get System Info Aug 28 14:36:36 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:36:36 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:36:36 volumio-tw volumio[817]: info: Discovery: Getting this device information Aug 28 14:36:36 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:36 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:36 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:36:37 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:36:37 volumio-tw volumio[817]: info: Received Get System Info Aug 28 14:36:37 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:36:37 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:36:37 volumio-tw volumio[817]: info: Discovery: Getting this device information Aug 28 14:36:37 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:37 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:37 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:36:37 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:37 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:39 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:39 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Aug 28 14:36:39 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:39 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:39 volumio-tw go-librespot[19703]: go-librespot daemon starting... Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=debug msg="app state loaded" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+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 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+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 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=info msg="zeroconf server listening on port 37059" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:39 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:36:39 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 14:36:39 volumio-tw volumio[817]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 14:36:39 volumio-tw volumio[817]: info: FusionDsp - no preset used Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=debug msg="obtained new client token: AAFYk8ptCXotpeKSkaBpbNZAYQ6mU9FeBMiBlYuZbiWB3lhdgZE6C5lSxyhAAt0CT46jjGUC2y1nUAHTxEm0mVFpPwaRky/dBUgIp0xCdg+nAzL0mRcfXvwCmjzMYxtPdXXc1PTvQijjneBqcVaijyoQhlXkofHUgln0gcMyT6Mk+OFwzopG/AIamT2eAGj9htruEOZlTFF/c6B5abXSN/3W/XTVaRkWF1fGsEFCT4bW0FO9LgkHbnvY" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:39 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:39+02:00" level=debug msg="completed challenge" Aug 28 14:36:40 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:40+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:40 volumio-tw volumio[817]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Aug 28 14:36:40 volumio-tw go-librespot[19703]: time="2026-08-28T14:36:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:40 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:40 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:40 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:40 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:43 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:43 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Aug 28 14:36:43 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:43 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:43 volumio-tw go-librespot[19728]: go-librespot daemon starting... Aug 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+02:00" level=debug msg="app state loaded" Aug 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+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 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+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 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+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 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+02:00" level=info msg="zeroconf server listening on port 44777" Aug 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:43 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:43+02:00" level=debug msg="obtained new client token: AAFMklVglk8HUitBjY/L57SKmFBtDcTFR+ov1e4N63XMb7VbEwwg7jM1mNBv+E1sV70PkoheH70Hgw2jNRzMPHMu2NZ9XQPFpjtF5cmRU23AXYW9pFjBdGu0+F9Cj+8WT89h7we5jSiXJBBsfTdmgBHVAfLui5uboF9btRy6x1GkozGMgcbwA6jQ8HYdx3fFbWmRMqqfau5iLvn+TLz7/GFCSGhTxE+rN9qIoJKunHSY/79vJSZwmwOL" Aug 28 14:36:43 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:44 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:44+02:00" level=debug msg="new websocket client" Aug 28 14:36:44 volumio-tw volumio[817]: info: Connection to go-librespot Websocket established Aug 28 14:36:44 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:44 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:44+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:44 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:44+02:00" level=debug msg="completed challenge" Aug 28 14:36:44 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:44+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:44 volumio-tw go-librespot[19728]: time="2026-08-28T14:36:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:44 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:44 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:44 volumio-tw volumio[817]: info: Connection to go-librespot Websocket closed Aug 28 14:36:44 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 28 14:36:44 volumio-tw volumio[817]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Aug 28 14:36:44 volumio-tw volumio5-onboarding[980]: time=2026-08-28T14:36:44.544+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 28 14:36:47 volumio-tw volumio[817]: info: Getting Spotify volume Aug 28 14:36:47 volumio-tw volumio[817]: (node:817) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:47 volumio-tw volumio[817]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 28 14:36:47 volumio-tw volumio[817]: (node:817) 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: 13) Aug 28 14:36:47 volumio-tw volumio[817]: 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 28 14:36:47 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:47 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:47 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:47 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:47 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:47 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Aug 28 14:36:47 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:47 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:47 volumio-tw go-librespot[19740]: go-librespot daemon starting... Aug 28 14:36:47 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:47+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:47 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:47+02:00" level=debug msg="app state loaded" Aug 28 14:36:47 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+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 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+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 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+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 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=info msg="zeroconf server listening on port 35359" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=debug msg="obtained new client token: AAF3snR3HCG2zSSsfXCZJ6TZMtOpuzKs/kngue0OWKQ4wGGs8hRuru5QCbLV/xuvCic0ic2EPvYW0xea9tAiWa7Jpptd/lF29UDBuZguvV9YowGYav2riiRkBYCDUY3xixMDkkAhflYgYTWWR7JmFpKd4ZrpwNH5cVOmwRJ4J+aEel6VZIPAUxit9lGCioz6CG2WXwu8F2Nt2mp9BVkUpLR3jtPDrZftPOTcwVYtpaY/hNnZe1iY6w==" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=debug msg="completed challenge" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:48 volumio-tw go-librespot[19740]: time="2026-08-28T14:36:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:48 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:48 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:50 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:50 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:51 volumio-tw systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 14:36:51 volumio-tw systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Aug 28 14:36:51 volumio-tw systemd[1]: Stopped go-librespot Daemon. Aug 28 14:36:51 volumio-tw systemd[1]: Started go-librespot Daemon. Aug 28 14:36:51 volumio-tw go-librespot[19765]: go-librespot daemon starting... Aug 28 14:36:51 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:51+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 14:36:51 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:51+02:00" level=debug msg="app state loaded" Aug 28 14:36:51 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+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 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+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 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+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 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=info msg="zeroconf server listening on port 40057" Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=debug msg="obtained new client token: AAEQIAf2fu9l+f1EIbaXRB5biCbUd2MoDqSuhEjYeD8eF3v/NODqUaDhOq0O4vr6mAOzWwjFhSdEj6wddd7CP19ZUmx8uOgbNQ9pgvfCjs1bHLW786KPyihJEuzFC6zKCOIqMoXT2YsMC+e7EaFvzYwbTRNMwjQh+9ze+wqTIHZbQqLfENnnGkfIUaAxAv8enoqalEpz9wrs1IgRD4kUsTTFtfsXDwKYMEptsKI1O9doXeBSzzPIRsQv" Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 14:36:52 volumio-tw volumio[817]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 14:36:52 volumio-tw volumio[817]: info: Received Get System Version Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 14:36:52 volumio-tw volumio[817]: info: Received Get System Info Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:36:52 volumio-tw volumio[817]: info: Discovery: Getting this device information Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::volumioGetState Aug 28 14:36:52 volumio-tw volumio[817]: info: CorePlayQueue::getTrack 0 Aug 28 14:36:52 volumio-tw volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=debug msg="completed keyexchange" Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=debug msg="completed challenge" Aug 28 14:36:52 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:52+02:00" level=info msg="authenticated AP" username="ro******et" Aug 28 14:36:53 volumio-tw go-librespot[19765]: time="2026-08-28T14:36:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 14:36:53 volumio-tw systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 14:36:53 volumio-tw systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 14:36:53 volumio-tw volumio[817]: info: Initializing connection to go-librespot Websocket Aug 28 14:36:53 volumio-tw volumio[817]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 14:36:53 volumio-tw volumio[817]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Aug 28 14:36:53 volumio-tw volumio[817]: info: FusionDsp - Stopping FusionDsp service Aug 28 14:36:53 volumio-tw volumio[817]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 14:36:53 volumio-tw volumio[817]: TypeError: Cannot read property 'stop' of null Aug 28 14:36:53 volumio-tw volumio[817]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Aug 28 14:36:53 volumio-tw volumio[817]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 28 14:36:53 volumio-tw volumio[817]: at /volumio/app/pluginmanager.js:956:12 Aug 28 14:36:53 volumio-tw volumio[817]: at ChildProcess.exithandler (child_process.js:299:7) Aug 28 14:36:53 volumio-tw volumio[817]: at ChildProcess.emit (events.js:315:20) Aug 28 14:36:53 volumio-tw volumio[817]: at maybeClose (internal/child_process.js:1048:16) Aug 28 14:36:53 volumio-tw volumio[817]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Aug 28 14:36:53 volumio-tw volumio[817]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 14:36:54 volumio-tw sudo[19785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-28 14:35 Aug 28 14:36:54 volumio-tw sudo[19785]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"