Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: TidalConnect service started! Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: [Metrics] CommandRouter: 29s 372.12ms Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::Close All Modals sent Dec 11 16:46:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::Close All Modals sent Dec 11 16:46:01 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 16:46:01 gbvpi-bedroom volumio[19242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 11 16:46:01 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:01 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 16:46:01 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 16:46:01 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 16:46:02 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. Dec 11 16:46:02 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:02 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:02 gbvpi-bedroom go-librespot[19807]: go-librespot daemon starting... Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="app state loaded" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=info msg="zeroconf server listening on port 46437" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="obtained new client token: AAB0ZhW77ZKFSPgTw3PJRayGTfnx7XNycWT48w9yrkNXMPvvX6Y0hXNizq4q4kM5HY7YNsXAuCmFhGHYY3u9Foq8JlJaKHxN+TLKCK6FnlyjFIDO7y/0SV9JFLc1Bonq9yfZ8vjd6FTE2CrhC3sCJRgFjiWgBw4Dd1MZdvfXhFeGohdlnXudNdtOZmhoZs8Zj5LqQUoI7iyjDEkS9Z/99q5+9kd5tggEL7jEu0K1wHk2t9XEWWbgwNMDgg==" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=debug msg="completed challenge" Dec 11 16:46:02 gbvpi-bedroom go-librespot[19808]: time="2025-12-11T16:46:02-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:02 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:02 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:03 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:03 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:05 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. Dec 11 16:46:05 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:05 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:05 gbvpi-bedroom go-librespot[19829]: go-librespot daemon starting... Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=debug msg="app state loaded" Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:05 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:05-05:00" level=info msg="zeroconf server listening on port 42707" Dec 11 16:46:06 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:06-05:00" level=debug msg="obtained new client token: AAAkCqDG1HMXUISUI795ZVSXKO/g8nt4O/CXu/RM2fEFG2YegGVQe/IygS4o8wvfQQ7Nm81EVEyVxR+VWSgL7Yv9XUyzUVpS2AITzEL9kBEfuvgS2zQAwgJ40Ap/HjY6zASyEpqyFzaoCKfHcZIP2DNbOuMxCDzFUlR8jfAzlaC4f+zmJ+8hrrLjLeXKKV8lAOuQojP94MG6vWIV3QBn/aeXmmvxuKK5WR+sfX9tt8qBg+ZLr0yR2v8=" Dec 11 16:46:06 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:06-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:06 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:06-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:06 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:06-05:00" level=debug msg="completed challenge" Dec 11 16:46:06 gbvpi-bedroom go-librespot[19830]: time="2025-12-11T16:46:06-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 16:46:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:06 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:06 gbvpi-bedroom volumio-remote-updater[775]: Test mode enabled Dec 11 16:46:06 gbvpi-bedroom volumio-remote-updater[775]: Alpha mode disabled Dec 11 16:46:06 gbvpi-bedroom volumio-remote-updater[775]: Alpha legacy test mode disabled Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

CAUTION

\n
    \n
  • This build may break your network
    • \n
\n

FIX

\n
    \n
  • Wireless and network handling
    • \n
\n","title":"Update v4.080","updateavailable":true} Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:06 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:07 gbvpi-bedroom volumio[19242]: info: BOOT COMPLETED Dec 11 16:46:08 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:08 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:08 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. Dec 11 16:46:09 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:09 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:09 gbvpi-bedroom go-librespot[19841]: go-librespot daemon starting... Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="app state loaded" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=info msg="zeroconf server listening on port 40865" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="obtained new client token: AABZKZCyBTqm/z6hBCAOIfXu5H35KYlGLIyRAEPPEuJ2izBEvrJn38msnDZDvRuJn8DUxFtzVp1oFBzq6ztkZ7ia92HzC5miX3r6lAiKDNBneKfeSKdLEOjVpcMsjNx+FT2F+8MVvmpBO737ZAeh/f8lmiEi7DhrtkAEoEPB/9MMbf/CxFoWfT8PlYC49Yxi/iz5Sryphb7Vok9QDNxV6rN0OQly0axUGZycnNhZ0gLMg4t8q10pyrOIjA==" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="connected to ap-gue1.spotify.com:443" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=debug msg="completed challenge" Dec 11 16:46:09 gbvpi-bedroom go-librespot[19842]: time="2025-12-11T16:46:09-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:09 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:09 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:09 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:10 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:12 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:12 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:12 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. Dec 11 16:46:12 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:13 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:13 gbvpi-bedroom go-librespot[19863]: go-librespot daemon starting... Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="app state loaded" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=info msg="zeroconf server listening on port 37741" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="obtained new client token: AAAv1bcdbsn+prQHTWOYrew7caRQv4KUNBajBs9x2kcQ8KJxs9GzzCy7tDS6G57yP6HiPmwohG8sf0IcAQ0vt9G8B+fEqtTmdU3tNBn5Lmug88y9HLJVCyb+geSpkdiAP3H0YwZJRBLFj2C96HG2vJv7r6xh7H0PDLrkFKdu1QoG0jbppwLl8rwap1YShFm4aVkb8drfBTQm7QSQZVFPN09fsCAGg9328dl5ynxU1MzZIVUKpHaxuDUdHA==" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=debug msg="completed challenge" Dec 11 16:46:13 gbvpi-bedroom go-librespot[19864]: time="2025-12-11T16:46:13-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:13 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:15 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:15 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:16 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 171. Dec 11 16:46:16 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:16 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:16 gbvpi-bedroom go-librespot[19871]: go-librespot daemon starting... Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=debug msg="app state loaded" Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:16 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:16-05:00" level=info msg="zeroconf server listening on port 35941" Dec 11 16:46:17 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:17-05:00" level=debug msg="obtained new client token: AACDP4kcdNzaa8YiGhU2cWL6srUiUMmw4WGp37qGr72H0IzQoD4SyqANf3ovO3HuKClL29DW/vN8gIFYLNwXzV4tn6X59zefOJM5fdiL/8zkODHqeXkk/wJAShtB3CZR3ezx2EnKmwnrbX/8/Mlfiza63BbTN7v+i4xXllIIVD1nihkpxsnpiAaTIfrSP/oNBGK8F8UbBEvlBM0bCAkuLoLegHJFD7JwtJdgIx7XYSPJFFpWm+mcR/c=" Dec 11 16:46:17 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:17-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:17 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:17-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:17 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:17-05:00" level=debug msg="completed challenge" Dec 11 16:46:17 gbvpi-bedroom go-librespot[19872]: time="2025-12-11T16:46:17-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:17 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:18 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:18 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:18 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 172. Dec 11 16:46:20 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:20 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:20 gbvpi-bedroom go-librespot[19882]: go-librespot daemon starting... Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="app state loaded" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=info msg="zeroconf server listening on port 35333" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="obtained new client token: AACCgCS1f5Kv8cJo8OtwWyZz6oA9cwJVB3Bf52yRhFXtGcfNqpMQ3KBgVnTdDMELcOBC+l/v5Y4RlAJsSil09/T3UnjEugsy0y0I+D6wYch6Utd9EfA9dcLI5ysqeV78JIAmfTkDEYwWuIHO6mRg+T7hR2m7N3Mexbb2KKhK+hxMRNz6EpvvVWUXcYQM9WVALDX0MDDea4KMrneDizST2OiWJmNebWVXPdRcNljQ4m1qIhOZI1P+n8G6KA==" Dec 11 16:46:20 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:20 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:20 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=debug msg="completed challenge" Dec 11 16:46:20 gbvpi-bedroom go-librespot[19883]: time="2025-12-11T16:46:20-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:20 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:21 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:21 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 173. Dec 11 16:46:24 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:24 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:24 gbvpi-bedroom go-librespot[19905]: go-librespot daemon starting... Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="app state loaded" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=info msg="zeroconf server listening on port 45841" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="obtained new client token: AACNgtKAw5JX/J3/xRcEp902gm2hMu5iddszqB6gecER7JkKO922BPkkz+2Oo5XqtLN/G+MrdQdE6OtjoOIEeZqEX6Gq2cwgtVFKgxC2lBHBDO0dO6cLgedopVFxorarhDGfq0jPB0cMPkliCVk0SS4ekTTovZ6TBxK9f3x1lebbYAkgw3sbhc+6BsJmQEYNUywgChaOUdBkHxGBghjGZzZqAqHDFFxVyA9FE+L6nLMMkjRAIhNrUDnIeg==" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=debug msg="completed challenge" Dec 11 16:46:24 gbvpi-bedroom go-librespot[19906]: time="2025-12-11T16:46:24-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:24 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:24 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:24 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:27 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:27 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:27 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 174. Dec 11 16:46:27 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:28 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:28 gbvpi-bedroom go-librespot[19913]: go-librespot daemon starting... Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="app state loaded" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=info msg="zeroconf server listening on port 43215" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="obtained new client token: AADrcdBUoKIeeaR6lGDsgoAiZSPbLjn7lc10uHI3wmC8oSeutuTnCRvfb8rD0aIiXLmTWmo3WHI8uWCF0Te3HnORe9sZXaG2z0mTfQlqUk9EShWbFEunCca2ox0H+QL9uxrE6Gv9BRHsF8ZM3bLElcCiKw4lNq9p44l3aZd5SnGfT+f6JCq0/kHFGhfY8TyQxn6tVnTQXcEIpQOnNzCx9yBCDVHbO3DPI96kZ3hVbMOkHT5ScJlB20iWyQ==" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=debug msg="completed challenge" Dec 11 16:46:28 gbvpi-bedroom go-librespot[19914]: time="2025-12-11T16:46:28-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:28 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:28 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:28 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:28 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:28 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:30 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:30 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:30 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:31 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 175. Dec 11 16:46:31 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:31 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:31 gbvpi-bedroom go-librespot[19921]: go-librespot daemon starting... Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=debug msg="app state loaded" Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:31 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:31-05:00" level=info msg="zeroconf server listening on port 43749" Dec 11 16:46:32 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:32-05:00" level=debug msg="obtained new client token: AADkVr0GE8dSV26mMjYejhWP30FP8Llezk4cfBZhWPEX+gs5zg5W8QV6oUaSJ/BfltGAfg7XgdP7QEbRDbcNwdcoC3F8+6MmoJG0LxiZu+Vzl27AlHwrs/4hP16f5WaobaGnQ6ne6xGo3yhEy9Cz1s37cd1KtMNh9uchXoMm4iUmB+iALVxLnfUkq4lDUzUfqm5TftpH8UseG1OIN2CdBpnE/vKrMRdAJLKvtRGoYzVqPdwSfSEyaks=" Dec 11 16:46:32 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:32-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:32 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:32-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:32 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:32-05:00" level=debug msg="completed challenge" Dec 11 16:46:32 gbvpi-bedroom go-librespot[19922]: time="2025-12-11T16:46:32-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:32 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:32 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:33 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:33 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 176. Dec 11 16:46:35 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:35 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:35 gbvpi-bedroom go-librespot[19944]: go-librespot daemon starting... Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="app state loaded" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=info msg="zeroconf server listening on port 46527" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="obtained new client token: AADfZLKqOve5pMAx8jUjMISndlMqtM8hKoERPT1wyG7ylXIPbCLalM+I5oROkPPFlSaKWKqZVbwgpxcgiKzKsXnwRf2wmdDuJJfyGUOfanaFiGiVLYwKKb0Xi0XvYQxmREbY8ywgSE93v+jc/WsFFXmlfeBczMt6eyLDhsD7mQJJNd0AxAWTR1dGZIEl9L2S8eVaVt6cFJYRaUp0RKlCxDrYTgmSac8w6tXxesUUzl4fSEVO+mzjat7p3Q==" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=debug msg="completed challenge" Dec 11 16:46:35 gbvpi-bedroom go-librespot[19945]: time="2025-12-11T16:46:35-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:35 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:36 gbvpi-bedroom volumio[19242]: info: UPDATER: Scheduling automatic update Dec 11 16:46:36 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Dec 11 16:46:36 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Dec 11 16:46:36 gbvpi-bedroom volumio[19242]: info: UPDATER: Auto update will take place at: Fri Dec 12 2025 05:33:32 GMT-0500 (Eastern Standard Time) Dec 11 16:46:36 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:36 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:38 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:38 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 177. Dec 11 16:46:38 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:39 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:39 gbvpi-bedroom go-librespot[19955]: go-librespot daemon starting... Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="app state loaded" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=info msg="zeroconf server listening on port 35969" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="obtained new client token: AABBpWOzj1zYYKbpz8nYcAZPQKwjPYpeqdV+xJ2wgV5Yqc1mrkXiNG+6DIDLa9z4l1Sna1YGR6ZdE/bAGmPrIzlMe1NdLi4QZPJvs8Z+mf+ME8pElyUY7t/p0Daulgu6iUgk+TO7Cpoxo1X6kHlMp29ZPe2h3d3gPfrhOkQo5REKUPRABMF+nP3whbjx8KHl5QbCdqZgcIDMKQ7bxe+hvASYVLEZT68HGVvJHbQIssqtp7tECZ7R216uyA==" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=debug msg="completed challenge" Dec 11 16:46:39 gbvpi-bedroom go-librespot[19956]: time="2025-12-11T16:46:39-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:39 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:39 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:39 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:40 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:40 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:40 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 178. Dec 11 16:46:42 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:42 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:42 gbvpi-bedroom go-librespot[19963]: go-librespot daemon starting... Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="app state loaded" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=info msg="zeroconf server listening on port 38003" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="obtained new client token: AACyPMGmiapmf6XYhNu+4CPL+l+Fhgv7A70P1XbOMpSluyTBDe6TnZrdzLDCdIJIzxSLaOPtDv77Nm6wXjIFam6BCO5yj+vrwvMFZlC9voIRlaruQN/EImUL6r+C/dWyxIbWDq984W/no++ol9FaaLvAGr7uAUpx3GUbDwfxbL0YLg4qZAgN8R7dP4gwmAn1WYijUFj/D+tXIQ/7fTNx12Mc93/DBndDMA17rHzJagK+fpk83tqmrlayNQ==" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=debug msg="completed challenge" Dec 11 16:46:42 gbvpi-bedroom go-librespot[19964]: time="2025-12-11T16:46:42-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:42 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:42 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:42 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:45 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:45 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:45 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 179. Dec 11 16:46:45 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:46 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:46 gbvpi-bedroom go-librespot[19985]: go-librespot daemon starting... Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="app state loaded" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=info msg="zeroconf server listening on port 43373" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="obtained new client token: AAA3sbEDRW1V6uwz88SEIFb4qEzNFcFLTknE33Jr39jMXO07AchgYrwwe+glDvrittkNbSutYDs/WM1nqocf8wKLdWqJ7kzh54MmVYAnp7k9RAWJr4hEhoGbqNSP/f+kot9enz2cePInXvASNgGcDlQkhHyJBSvZ4MnETH8mebeEyD1rkMaXAZBAS8kNEA59XigHEuxIRkjlzQ3TBxcgzux4yN1BZypoGKWY3AFTVa/uj/SJUa3Ny3qeIw==" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=debug msg="completed challenge" Dec 11 16:46:46 gbvpi-bedroom go-librespot[19986]: time="2025-12-11T16:46:46-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:46 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:48 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:48 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:48 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:46:48 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:48 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:49 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 180. Dec 11 16:46:49 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:49 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:49 gbvpi-bedroom go-librespot[19993]: go-librespot daemon starting... Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=debug msg="app state loaded" Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:49 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:49-05:00" level=info msg="zeroconf server listening on port 45171" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=debug msg="obtained new client token: AAAQIfopwcVNU/9E7wv7PZCiYlpy3vDZslo/C0EJVIp7jR4yhOf3trN81OJQ8BGnH0y+QGh9tASVTNmgvIICo/OtvyBUAtI41GcPu0q2YRgwnpZ5483DMqxNPvcU9ERyHQHLPC7CWqfbqn4BKZVrJvPxLhhe8tFLs75zhTTaArDhgB3xaXJlA8uY5gfjokFCbUaLe62qmIMv6Z3v2lR8KftTjsUqE5Cyh5TuHrivEo+iIPfl8hmZRDY=" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=debug msg="connected to ap-gue1.spotify.com:80" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=debug msg="completed challenge" Dec 11 16:46:50 gbvpi-bedroom go-librespot[19994]: time="2025-12-11T16:46:50-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:50 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:50 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:50 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:46:51 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:51 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:53 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 181. Dec 11 16:46:53 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:53 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:53 gbvpi-bedroom go-librespot[20015]: go-librespot daemon starting... Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="app state loaded" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=info msg="zeroconf server listening on port 34093" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="obtained new client token: AACa3/WCiCOr2VRMICLvfIhNtabVuuPmmsnuipiQ0B5JFkJUk8iytOHg48k8fn0aT/5/2b6uFQTg6yDzt908oCq71bM8b+m/9PsIbfs1NZ5sOmU2qgTCE9IjXCMT+v6gyh4MW8kzwThQ6z6cwX/PerYhC5Oh4APwHFK9KIXBp1Km/dHBqApSLUJTbJsqPCj77eU4sQGhku3VCqufgosh7yB+aEBj5JKdLTh+bnIGDbbRIS2D/z8YoxEZnQ==" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=debug msg="completed challenge" Dec 11 16:46:53 gbvpi-bedroom go-librespot[20016]: time="2025-12-11T16:46:53-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:53 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:53 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:54 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:54 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:56 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 182. Dec 11 16:46:56 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:57 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:46:57 gbvpi-bedroom go-librespot[20025]: go-librespot daemon starting... Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="app state loaded" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=info msg="zeroconf server listening on port 39689" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="obtained new client token: AACBadcnURTf+8FiUbJZaoCD0ebPn6UyoMCkiI4VJcyQ7EDrs263TEdutqHihp6EKU3u6bIu5WUWDIOVe7BbsNEQqxNbYsKqJ0jQbvKukFoKeksAuY7+tP7grD0Jtq4MPtd9Jk6irBqhZbhDTmcHKzpRUEmh7RHjYLKhGz+SByEHg8X3IOes3xWCy7WuJL4C9mxeXn5xA5ZKJR/AUy3kB3XfrNWV4igNmFe6LlEiTa7fYhXwj9Zn2LO/mg==" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="completed keyexchange" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=debug msg="completed challenge" Dec 11 16:46:57 gbvpi-bedroom go-librespot[20026]: time="2025-12-11T16:46:57-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:46:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:46:57 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:46:57 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:46:57 gbvpi-bedroom volumio[19242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:46:58 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:47:00 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 183. Dec 11 16:47:00 gbvpi-bedroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:47:00 gbvpi-bedroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 11 16:47:00 gbvpi-bedroom go-librespot[20037]: go-librespot daemon starting... Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=info msg="running go-librespot 0.4.0" Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=debug msg="app state loaded" Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 11 16:47:00 gbvpi-bedroom volumio[19242]: info: CoreCommandRouter::volumioGetState Dec 11 16:47:00 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:47:00 gbvpi-bedroom volumio[19242]: info: Listing playlists Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=info msg="zeroconf server listening on port 34135" Dec 11 16:47:00 gbvpi-bedroom volumio[19242]: info: Initializing connection to go-librespot Websocket Dec 11 16:47:00 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:00-05:00" level=debug msg="new websocket client" Dec 11 16:47:00 gbvpi-bedroom volumio[19242]: info: Connection to go-librespot Websocket established Dec 11 16:47:01 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:01-05:00" level=debug msg="obtained new client token: AAA/I1oSLPK+BhT4t3mN2BCZotPC92cR3vm5BY1oUF3hqbDDt1xdfhwXoFKMt1QyDSuS0vpJ90IYaiC6LqRBYV7lZFYPaYTS16t7uIDR36LdnVZLs3aUglVNDn9h1Rmg/0dG0+C/FhmLO1eziJuRc0XbdCkwqKA3X+Q0/ClwkF0hCB0lEjrvGSI6aaeKjt+Df0bTuQRHmybMe2l3LY7s8CjTikPvk5cAVXSL0rdr8QqfbSlvgzf8s6Y=" Dec 11 16:47:01 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:01-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Dec 11 16:47:01 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:01-05:00" level=debug msg="completed keyexchange" Dec 11 16:47:01 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:01-05:00" level=debug msg="completed challenge" Dec 11 16:47:01 gbvpi-bedroom go-librespot[20038]: time="2025-12-11T16:47:01-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 11 16:47:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 16:47:01 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 16:47:01 gbvpi-bedroom volumio[19242]: info: Connection to go-librespot Websocket closed Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: info: Getting Spotify volume Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: errno: -111, Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: code: 'ECONNREFUSED', Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: syscall: 'connect', Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: address: '127.0.0.1', Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: port: 9879, Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: response: undefined Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: } Dec 11 16:47:03 gbvpi-bedroom volumio[19242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 16:47:04 gbvpi-bedroom sudo[20073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 16:46' Dec 11 16:47:04 gbvpi-bedroom sudo[20073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="6a0bffa825dc2af90b21170949f5219ba5ab5701" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Dec 10 09:27:23 UTC 2025" VOLUMIO_VERSION="4.075" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bd01f93a654c7068f4553fbd69478e2"