-- Logs begin at Sat 2026-03-28 15:11:48 EET, end at Sat 2026-03-28 18:46:05 EET. -- Mar 28 18:45:01 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:01 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:01 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1821. Mar 28 18:45:01 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:01 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:01 minidsp-shd go-librespot[5927]: go-librespot daemon starting... Mar 28 18:45:01 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:01+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:01 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:01+02:00" level=debug msg="app state loaded" Mar 28 18:45:01 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:01+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:01 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=info msg="zeroconf server listening on port 38287" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="obtained new client token: AAAviDi2/WsJDyLifpjVM1lihvY8wyzJ5DfUa7nR1ZTOSNSOdGHUI19GrXbpCRdRK9PhVfqPdVFc8zizj0CS+vqGxjP+Qdex0SRYEmmbYdxO2mu/dLzAUNK99uyDBpzoYjU6wrIhzKpu0ilnw1tgOgFHHp1OC8ilUqOHVA7xxNZx+eSbBIIgswVZM2OVbFTmhI+Ht+C/dniGUkSKPVUAjXwivM7RwwnnrJYTW1g/jlUccKMdQSBOMtI=" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=debug msg="completed challenge" Mar 28 18:45:02 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:02+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:03 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:03+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Mar 28 18:45:03 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:03 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:03+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:03 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:03+02:00" level=debug msg="completed challenge" Mar 28 18:45:03 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:03+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:04 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:04+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:04 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:04+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:04 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:04+02:00" level=debug msg="completed challenge" Mar 28 18:45:04 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:04 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:04+02:00" level=debug msg="new websocket client" Mar 28 18:45:04 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:04 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:04+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:05 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:05+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 28 18:45:05 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:05+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:05 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:05+02:00" level=debug msg="completed challenge" Mar 28 18:45:05 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:05+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:06 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Mar 28 18:45:06 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:06+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:06 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:06+02:00" level=debug msg="completed challenge" Mar 28 18:45:06 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:06+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:07 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:07+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:07 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:07+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:07 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:07+02:00" level=debug msg="completed challenge" Mar 28 18:45:07 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:45:07 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:45:07 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:08 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:08+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:08 minidsp-shd go-librespot[5927]: time="2026-03-28T18:45:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:45:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:45:08 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 28 18:45:08 minidsp-shd volumio[6415]: at connResetException (internal/errors.js:639:14) Mar 28 18:45:08 minidsp-shd volumio[6415]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 28 18:45:08 minidsp-shd volumio[6415]: at Socket.emit (events.js:412:35) Mar 28 18:45:08 minidsp-shd volumio[6415]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 28 18:45:08 minidsp-shd volumio[6415]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 28 18:45:08 minidsp-shd volumio[6415]: (node:6415) 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: 1955) Mar 28 18:45:08 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:45:11 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:11 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:11 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:11 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1822. Mar 28 18:45:11 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:11 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:11 minidsp-shd go-librespot[5951]: go-librespot daemon starting... Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="app state loaded" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=info msg="zeroconf server listening on port 44639" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="obtained new client token: AACKT5JxWFmPkO1XBw00si8570akkv0XqFa3nfoSjTiJ/9Zdg6NLNuWQOseY8yc1Lw1bZGi/B5V1VQtFbDQ9LGQ007bEoPG2G44enbrILXTyO0lT+mDxFrVqmpjuTwbbYBx8ZlLHbEg3eiUSbr/h9CDgNfsiywYNIKR59XVRLUNig3dXXsL8AIZZX3fcEKwTb06Hxwgjb/JSuO3bLNJfUe3eZxUbgA6oOHf+0akGXpm64fvC9gYrck0=" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=debug msg="completed challenge" Mar 28 18:45:11 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:11+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:12 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:13 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:13+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:13 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:13+02:00" level=debug msg="completed challenge" Mar 28 18:45:13 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:13+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:14 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:14+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:14 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:14 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:14+02:00" level=debug msg="new websocket client" Mar 28 18:45:14 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:14 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:14+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:14 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:14+02:00" level=debug msg="completed challenge" Mar 28 18:45:14 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:14+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:14 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:14+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:15 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:15+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:15 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:15+02:00" level=debug msg="completed challenge" Mar 28 18:45:15 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:15+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:15 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:15+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=debug msg="completed challenge" Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:16 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:16+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:17 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:17+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:17 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:17+02:00" level=debug msg="completed challenge" Mar 28 18:45:17 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:45:17 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:45:17 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:17 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:17+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:17 minidsp-shd go-librespot[5951]: time="2026-03-28T18:45:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:17 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 28 18:45:17 minidsp-shd volumio[6415]: at connResetException (internal/errors.js:639:14) Mar 28 18:45:17 minidsp-shd volumio[6415]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 28 18:45:17 minidsp-shd volumio[6415]: at Socket.emit (events.js:412:35) Mar 28 18:45:17 minidsp-shd volumio[6415]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 28 18:45:17 minidsp-shd volumio[6415]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 28 18:45:17 minidsp-shd volumio[6415]: (node:6415) 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: 1956) Mar 28 18:45:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:45:17 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:45:17 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:45:20 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:20 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:20 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1823. Mar 28 18:45:20 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:20 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:20 minidsp-shd go-librespot[5973]: go-librespot daemon starting... Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=debug msg="app state loaded" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=info msg="zeroconf server listening on port 35395" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:20 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:20+02:00" level=debug msg="obtained new client token: AACIx/o+sXBsqRDtGqjvDkjGJ+wh65wkDKF+mgedWyGCa+Sn017hB56am79yWDrwrwX2h+jUWjsu/hiW9Tbxz6osgD/minvXHXp1bRQk7P2QzqyvBWm/hgPsyH3U687rQ90XyKRBUcGujFt59GEYtlhVixItjz3XAaHd9iwxrL6V09uo8C39UnYYvanCBEwAdpefs1bPX35TRVoP2XtOIHGKYTgqEJ6v3tiaOZpc+1KnqCmNpf4zfIU=" Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=debug msg="completed challenge" Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:21 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:21+02:00" level=debug msg="completed challenge" Mar 28 18:45:22 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:22+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:22 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:22+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:22 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:22+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:22 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:22+02:00" level=debug msg="completed challenge" Mar 28 18:45:22 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:22+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:23 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:23 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:23+02:00" level=debug msg="new websocket client" Mar 28 18:45:23 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:23 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:23+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:23 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:23+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:23 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:23+02:00" level=debug msg="completed challenge" Mar 28 18:45:23 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:23+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:24 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:24+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 28 18:45:24 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:24+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:24 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:24+02:00" level=debug msg="completed challenge" Mar 28 18:45:24 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:24+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:25 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:25+02:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Mar 28 18:45:25 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:25 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:25+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:25 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:25+02:00" level=debug msg="completed challenge" Mar 28 18:45:25 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:25+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:25 minidsp-shd go-librespot[5973]: time="2026-03-28T18:45:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:45:25 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:45:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:45:26 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:45:26 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:26 minidsp-shd volumio[6415]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 28 18:45:26 minidsp-shd volumio[6415]: (node:6415) 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: 1957) Mar 28 18:45:26 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:45:26 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source SoundCloud Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source YouTube Music Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source 80s80s Radio Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source Mother Earth Radio Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source Presets Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source TIDAL Mar 28 18:45:28 minidsp-shd volumio[6415]: Cannot find translation for source Spotify Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioStop Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreStateMachine::stop Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreStateMachine::serviceStop Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreCommandRouter::serviceStop Mar 28 18:45:28 minidsp-shd volumio[6415]: info: [1774716328777] inputs::stop Mar 28 18:45:28 minidsp-shd volumio[6415]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} Mar 28 18:45:28 minidsp-shd volumio[6415]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:45:28 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:28 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1824. Mar 28 18:45:28 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:28 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:28 minidsp-shd go-librespot[5996]: go-librespot daemon starting... Mar 28 18:45:28 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:28+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:28 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:28+02:00" level=debug msg="app state loaded" Mar 28 18:45:28 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:28+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:28 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreCommandRouter::servicePushState Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreStateMachine::pushState Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioPushState Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:29 minidsp-shd volumio[6415]: info: MRS: Pushing multiroomSync output update for this device Mar 28 18:45:29 minidsp-shd volumio[6415]: info: MRS: Pushing multiroomSync output Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=info msg="zeroconf server listening on port 37075" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 28 18:45:29 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source SoundCloud Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source YouTube Music Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source 80s80s Radio Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source Mother Earth Radio Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source Presets Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source TIDAL Mar 28 18:45:29 minidsp-shd volumio[6415]: Cannot find translation for source Spotify Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="obtained new client token: AAB6o0CVqxDq3Plg/7Q2fSj3WCxBAUAMvbgAkLLl2/TyAMfuS+qXnAFy+qFTtL0fGKu+7NuE18RTbuuhKH5sPhJfXdV8+qgvGJWHs4CibcyAnKTtBy7p5L5AytqkxuCY3DuAyqpejWEU3lkbbjJUbx/mKSfat7XLhvLB9MZaQOo8h8gRMB6v2VrsP6rI2+DDaTnou6sG/C158tiz7hOlrByNYSyS8apf0QKpEQRfTjE2WguHvUnsVYA=" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=debug msg="completed challenge" Mar 28 18:45:29 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:29+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:30 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:30+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Mar 28 18:45:30 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:30+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:30 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:30+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:30 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:30+02:00" level=debug msg="completed challenge" Mar 28 18:45:30 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:30+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:31 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:31+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:31 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:31+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:31 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:31+02:00" level=debug msg="completed challenge" Mar 28 18:45:31 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:31+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:31 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:31 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:31+02:00" level=debug msg="new websocket client" Mar 28 18:45:31 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:32 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:32+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 28 18:45:32 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:32+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:32 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:32+02:00" level=debug msg="completed challenge" Mar 28 18:45:32 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:32+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:33 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Mar 28 18:45:33 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:33+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:33 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:33+02:00" level=debug msg="completed challenge" Mar 28 18:45:33 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:33+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:33 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:33+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Mar 28 18:45:33 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:33+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:34 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:34+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:34 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:34+02:00" level=debug msg="completed challenge" Mar 28 18:45:34 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:34+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:34 minidsp-shd go-librespot[5996]: time="2026-03-28T18:45:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:45:34 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:45:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:45:34 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:45:34 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:34 minidsp-shd volumio[6415]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 28 18:45:34 minidsp-shd volumio[6415]: (node:6415) 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: 1958) Mar 28 18:45:34 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:45:34 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:37 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:37 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1825. Mar 28 18:45:37 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:37 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:37 minidsp-shd go-librespot[6004]: go-librespot daemon starting... Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=debug msg="app state loaded" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=info msg="zeroconf server listening on port 46707" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:37 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:37+02:00" level=debug msg="obtained new client token: AACl+ltSWVwKmDJe0VNtqYMGzjDbRxcz0NFjt+fMjYvUwsvC0B8istj9oPzpAfAchsAMab/Vbse+Lhr9blrwlIckkwhYxvW032bacdv7iCzPBMfZykxKwiV62Ry5NljHEKBYMceTc/ZIaN7O9t7KaaFe2jBMx8hsY0os9YwCEE9lHn24dnTHm5sbmnNYODq9Vx7b/Ij1eyNa+kv2qr4N2cm4o3I3RS7IzKHp/HErTB85vVuK0ox4Uz8=" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=debug msg="completed challenge" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=debug msg="completed challenge" Mar 28 18:45:38 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:38+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:39 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:39+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.65.9:80: connect: connection refused" Mar 28 18:45:39 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:39+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:39 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:39+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:39 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:39+02:00" level=debug msg="completed challenge" Mar 28 18:45:40 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:40+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:40 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:40 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:40+02:00" level=debug msg="new websocket client" Mar 28 18:45:40 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:40 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:40+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 28 18:45:40 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:40+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:40 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:40+02:00" level=debug msg="completed challenge" Mar 28 18:45:41 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:41+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:41 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:41+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Mar 28 18:45:42 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:42+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:42 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:42+02:00" level=debug msg="completed challenge" Mar 28 18:45:42 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:42+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:43 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:43+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:43 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:43+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:43 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:43+02:00" level=debug msg="completed challenge" Mar 28 18:45:43 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:45:43 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:45:43 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:43 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:43+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:43 minidsp-shd go-librespot[6004]: time="2026-03-28T18:45:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:45:43 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:45:43 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 28 18:45:43 minidsp-shd volumio[6415]: at connResetException (internal/errors.js:639:14) Mar 28 18:45:43 minidsp-shd volumio[6415]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 28 18:45:43 minidsp-shd volumio[6415]: at Socket.emit (events.js:412:35) Mar 28 18:45:43 minidsp-shd volumio[6415]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 28 18:45:43 minidsp-shd volumio[6415]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 28 18:45:43 minidsp-shd volumio[6415]: (node:6415) 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: 1959) Mar 28 18:45:43 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:45:46 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:46 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:46 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:46 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1826. Mar 28 18:45:46 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:46 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:46 minidsp-shd go-librespot[6065]: go-librespot daemon starting... Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=debug msg="app state loaded" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=info msg="zeroconf server listening on port 43213" Mar 28 18:45:46 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:46+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:47 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:47+02:00" level=debug msg="obtained new client token: AABuL4jesiRt4yKjCmNWtTFbF/PonnuWeyX4f8wu0NRhZMuYv7jtycCCQQHl3G/CbT3BFThXGL4hZecrrBncu3hrPrG4jkYap+jJhmHYVQlBglNjqjYVY561rONaUApL7qaICeKRG1N9v7MHspLeUC5bGoVj8hLNip0b0zlS1llhJvtkjyw+LkFYO9Pmc3C+oRrW6B+l2yZx+t31RZBVO0EervB40elqxDwmbBOBql/tGBJZTPnA" Mar 28 18:45:47 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:47+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:47 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:47+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:47 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:47+02:00" level=debug msg="completed challenge" Mar 28 18:45:48 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:48+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:48 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:48+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:48 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:48+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:48 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:48+02:00" level=debug msg="completed challenge" Mar 28 18:45:48 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:48+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:49 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:49 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:49+02:00" level=debug msg="new websocket client" Mar 28 18:45:49 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:49 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:49+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:49 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:49+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:49 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:49+02:00" level=debug msg="completed challenge" Mar 28 18:45:49 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:49+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:50 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:50+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:50 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:50+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:50 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:50+02:00" level=debug msg="completed challenge" Mar 28 18:45:50 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:50+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:51 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:51+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 28 18:45:51 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:51+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:51 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:51+02:00" level=debug msg="completed challenge" Mar 28 18:45:51 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:51+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:52 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Mar 28 18:45:52 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:52+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:52 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:52+02:00" level=debug msg="completed challenge" Mar 28 18:45:52 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:45:52 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:45:52 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:52+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:52 minidsp-shd go-librespot[6065]: time="2026-03-28T18:45:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:52 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:45:52 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:45:52 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:45:52 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 28 18:45:52 minidsp-shd volumio[6415]: at connResetException (internal/errors.js:639:14) Mar 28 18:45:52 minidsp-shd volumio[6415]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 28 18:45:52 minidsp-shd volumio[6415]: at Socket.emit (events.js:412:35) Mar 28 18:45:52 minidsp-shd volumio[6415]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 28 18:45:52 minidsp-shd volumio[6415]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 28 18:45:52 minidsp-shd volumio[6415]: (node:6415) 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: 1960) Mar 28 18:45:52 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:45:55 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:55 minidsp-shd volumio[6415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 18:45:55 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 18:45:55 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1827. Mar 28 18:45:55 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 28 18:45:55 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 28 18:45:55 minidsp-shd go-librespot[6088]: go-librespot daemon starting... Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=info msg="running go-librespot 0.7.1" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=debug msg="app state loaded" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=debug msg="stored credentials not found" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=info msg="zeroconf server listening on port 34147" Mar 28 18:45:55 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:55+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 18:45:56 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:56+02:00" level=debug msg="obtained new client token: AADo607hFkgmFiQYVu0mkXhsfyw6VCYWqr5NGcub7R1vuCEDsIlNURavTMdErXAZiqLKGBFfyru2rgIL8hCQKVzGkMAYjjaEXpiwipn5bcj55FuVHZKtyp9mo7PUY+Qqpm690FYblrpoeIU8RVA3/ZtX3GaMISoKYme8/iH+lNGwg7qiRzEoq1XhECOFm4t+3E3N0J9zFaAOX39BUfMgyutCAqlOBrPGSmP1BWkGTgS59DWxdGMiSyg=" Mar 28 18:45:56 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:45:56 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:56+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:56 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:56+02:00" level=debug msg="completed challenge" Mar 28 18:45:56 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:56+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:57 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:57+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 28 18:45:57 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:57+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:57 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:57+02:00" level=debug msg="completed challenge" Mar 28 18:45:57 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:57+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:58 minidsp-shd volumio[6415]: info: Initializing connection to go-librespot Websocket Mar 28 18:45:58 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:58+02:00" level=debug msg="new websocket client" Mar 28 18:45:58 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket established Mar 28 18:45:58 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:58+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 28 18:45:58 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:58+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:58 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:58+02:00" level=debug msg="completed challenge" Mar 28 18:45:58 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:58+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:45:59 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:59+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 28 18:45:59 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:59+02:00" level=debug msg="completed keyexchange" Mar 28 18:45:59 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:59+02:00" level=debug msg="completed challenge" Mar 28 18:45:59 minidsp-shd go-librespot[6088]: time="2026-03-28T18:45:59+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:46:00 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:00+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 28 18:46:00 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:00+02:00" level=debug msg="completed keyexchange" Mar 28 18:46:00 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:00+02:00" level=debug msg="completed challenge" Mar 28 18:46:00 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:00+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:46:00 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to 192.168.1.5 from 192.168.1.8 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 18:46:01 minidsp-shd volumio[6415]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Mar 28 18:46:01 minidsp-shd volumio[6415]: info: Received Get System Info Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:46:01 minidsp-shd volumio[6415]: info: Discovery: Getting this device information Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:46:01 minidsp-shd volumio[6415]: info: Listing playlists Mar 28 18:46:01 minidsp-shd volumio[6415]: xcb_connection_has_error() returned true Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 18:46:01 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:01+02:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Mar 28 18:46:01 minidsp-shd volumio[6415]: xcb_connection_has_error() returned true Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Mar 28 18:46:01 minidsp-shd volumio[6415]: info: Getting Spotify volume Mar 28 18:46:01 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 18:46:01 minidsp-shd volumio[6415]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:46:01 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:46:02 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:02+02:00" level=debug msg="completed keyexchange" Mar 28 18:46:02 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:02+02:00" level=debug msg="completed challenge" Mar 28 18:46:02 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:02+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:46:02 minidsp-shd go-librespot[6088]: time="2026-03-28T18:46:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 28 18:46:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 18:46:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 18:46:02 minidsp-shd volumio[6415]: (node:6415) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 28 18:46:02 minidsp-shd volumio[6415]: at connResetException (internal/errors.js:639:14) Mar 28 18:46:02 minidsp-shd volumio[6415]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 28 18:46:02 minidsp-shd volumio[6415]: at Socket.emit (events.js:412:35) Mar 28 18:46:02 minidsp-shd volumio[6415]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 28 18:46:02 minidsp-shd volumio[6415]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 28 18:46:02 minidsp-shd volumio[6415]: (node:6415) 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: 1961) Mar 28 18:46:02 minidsp-shd volumio[6415]: info: Connection to go-librespot Websocket closed Mar 28 18:46:02 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:46:02 minidsp-shd volumio[6415]: info: Received Get System Info Mar 28 18:46:02 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:46:02 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:46:02 minidsp-shd volumio[6415]: info: Discovery: Getting this device information Mar 28 18:46:02 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:46:02 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:46:03 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:46:04 minidsp-shd volumio[6415]: info: Received Get System Info Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:46:04 minidsp-shd volumio[6415]: info: Discovery: Getting this device information Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioGetState Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreCommandRouter::volumioSeek Mar 28 18:46:04 minidsp-shd volumio[6415]: info: CoreStateMachine::seek Mar 28 18:46:04 minidsp-shd volumio[6415]: info: [1774716364645] inputs::seek to 0 Mar 28 18:46:04 minidsp-shd volumio[6415]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 18:46:04 minidsp-shd volumio[6415]: TypeError: this.sendSpopCommand is not a function Mar 28 18:46:04 minidsp-shd volumio[6415]: at inputs.seek (/volumio/app/plugins/music_service/inputs/inputs_real:1:2486) Mar 28 18:46:04 minidsp-shd volumio[6415]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1098:20) Mar 28 18:46:04 minidsp-shd volumio[6415]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1446:28) Mar 28 18:46:04 minidsp-shd volumio[6415]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:177:33) Mar 28 18:46:04 minidsp-shd volumio[6415]: at Socket.emit (events.js:400:28) Mar 28 18:46:04 minidsp-shd volumio[6415]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 28 18:46:04 minidsp-shd volumio[6415]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 28 18:46:04 minidsp-shd volumio[6415]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 18:46:05 minidsp-shd sudo[6124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 18:45 Mar 28 18:46:05 minidsp-shd sudo[6124]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 10:55:12 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="0dc70286d49f98e8b92f179804dc6fb6"