Feb 25 19:39:00 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:00 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:01 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 25 19:39:01 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 19:39:01 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 25 19:39:01 volumio volumio-remote-updater[26837]: Test mode disabled
Feb 25 19:39:01 volumio volumio-remote-updater[26837]: Alpha mode disabled
Feb 25 19:39:01 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled
Feb 25 19:39:01 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 25 19:39:02 volumio volumio[9528]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for CPU utilization spike
\n- Fix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\n- Add BLE onboarding plugin
\n- Enable vc4-kms-v3d for all compatible RPi models
\n- Update Tidal SDK
\n
\n","title":"Update v4.096","updateavailable":true}
Feb 25 19:39:02 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 25 19:39:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51.
Feb 25 19:39:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:02 volumio go-librespot[10075]: go-librespot daemon starting...
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=debug msg="app state loaded"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:02 volumio go-librespot[10076]: time="2026-02-25T19:39:02-06:00" level=info msg="zeroconf server listening on port 44995"
Feb 25 19:39:03 volumio go-librespot[10076]: time="2026-02-25T19:39:03-06:00" level=debug msg="obtained new client token: AAB0DJh/JcHxcD54kI/dACnCrMsNVRLJhH+qNO8EjEvlSmIQdYRjZNve4pmMP2MjhKtIx70RcL8iEEsUHW5d4DMMR2zWqyFvITFaPUVizFt/VOx5bxiWejsXxJcvnTh/eYZa9jk5iAHHPARhyCZt3J+P/MtWc3TYRg5XwrmO1hmC0su3OLf3t9sRZWZkR1mgEXr+wtN+hMKAspilwdQvdfzsn/Hd623ZRYaYexkn4nqkGs7P1QLrheM="
Feb 25 19:39:03 volumio go-librespot[10076]: time="2026-02-25T19:39:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:03 volumio go-librespot[10076]: time="2026-02-25T19:39:03-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:03 volumio go-librespot[10076]: time="2026-02-25T19:39:03-06:00" level=debug msg="completed challenge"
Feb 25 19:39:03 volumio go-librespot[10076]: time="2026-02-25T19:39:03-06: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 "
Feb 25 19:39:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:03 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:03 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:04 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 25 19:39:04 volumio volumio[9528]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Feb 25 19:39:04 volumio volumio[9528]: info: CoreCommandRouter::volumioGetState
Feb 25 19:39:04 volumio volumio[9528]: info: CorePlayQueue::getTrack 0
Feb 25 19:39:06 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:06 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52.
Feb 25 19:39:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:06 volumio go-librespot[10083]: go-librespot daemon starting...
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="app state loaded"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=info msg="zeroconf server listening on port 44873"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="obtained new client token: AAAvZWTTg5hbYcWbC08XgCvfSOaVPRdXjWY0SnhGzH4h9A20SYf/TdUfyfCBSUiiPVWo+GlwtmPfgF/rm21Y15a9ttxUiuaWUyuxYdYYkvx0hs81x9HLnllwKL3QtxqF5mgYtxtEJPJkVwo121gxzHqEoZVrqWEytsJOTge+r/j+Ia06wSjMIqTxU/SglvbqjW2Wi7n1xkzb+mYnam2Adklrkep/vM4IQuAvRcCPDOcm/9bMMvYMzcyuXg=="
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06:00" level=debug msg="completed challenge"
Feb 25 19:39:06 volumio go-librespot[10084]: time="2026-02-25T19:39:06-06: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 "
Feb 25 19:39:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 25 19:39:09 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 25 19:39:09 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:09 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53.
Feb 25 19:39:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:10 volumio go-librespot[10105]: go-librespot daemon starting...
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="app state loaded"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:10 volumio volumio[9528]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 25 19:39:10 volumio volumio[9528]: info: Completed starting MyVolumio Plugin
Feb 25 19:39:10 volumio volumio[9528]: [Metrics] CommandRouter: 55s 326.35ms
Feb 25 19:39:10 volumio volumio[9528]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 25 19:39:10 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 25 19:39:10 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 25 19:39:10 volumio volumio[9528]: info: CoreCommandRouter::Close All Modals sent
Feb 25 19:39:10 volumio volumio[9528]: info: CoreCommandRouter::Close All Modals sent
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=info msg="zeroconf server listening on port 46219"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="obtained new client token: AACM5yq0m1BRBUEuVhRu2c59lO7EevX10RHe0rgaCy/n5N8GJ0qszFd1Jpt55IncVC2lhW6QddU3mVdI3ldfhLHIeGPuuNj+CVl4dPLzAJ2e18LL91qk4q0as/lJ4PX0Q1y1FwBEMZyGT8LMH2hYOLd8WLLbsdQCK1zzhDBn1c0L/YE0YAsj8uTTK0RbE2Nl53FOmQw0Njzpq1fCJrtcWMz9y0QXm9uP2LuzoLwvqvrL+3qeG9o4LKo+9Q=="
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06:00" level=debug msg="completed challenge"
Feb 25 19:39:10 volumio go-librespot[10106]: time="2026-02-25T19:39:10-06: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 "
Feb 25 19:39:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:11 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 25 19:39:11 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 25 19:39:11 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 25 19:39:12 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:12 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54.
Feb 25 19:39:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:13 volumio go-librespot[10119]: go-librespot daemon starting...
Feb 25 19:39:13 volumio go-librespot[10120]: time="2026-02-25T19:39:13-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:13 volumio go-librespot[10120]: time="2026-02-25T19:39:13-06:00" level=debug msg="app state loaded"
Feb 25 19:39:13 volumio go-librespot[10120]: time="2026-02-25T19:39:13-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:13 volumio go-librespot[10120]: time="2026-02-25T19:39:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=info msg="zeroconf server listening on port 33135"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="obtained new client token: AABIdlkVTx+MzjEgDCFCqBvWxFlPsoIROgSTsN27a2moqtRlDBAJpUhVZOhhmoikag0oybzVSXQmrJ5UM+9BI2RTIrsb067puwHjX8zjy3MtLccqaaKdzl1RUwbTYnDNdxc4p+4pHJMTkK9U0tMBD3TNmhRNr5pbD2ckj4jjNOQBrhULRwRKL4dGE0rNZ11oSBkb1rtaFf5Nd3W0FG0aItt1dolUSAIenh9HUjU2+0319IWCHHZ2dIStxA=="
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06:00" level=debug msg="completed challenge"
Feb 25 19:39:14 volumio go-librespot[10120]: time="2026-02-25T19:39:14-06: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 "
Feb 25 19:39:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:15 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:15 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:17 volumio volumio[9528]: info: BOOT COMPLETED
Feb 25 19:39:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55.
Feb 25 19:39:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:17 volumio go-librespot[10142]: go-librespot daemon starting...
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=debug msg="app state loaded"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 25 19:39:17 volumio go-librespot[10143]: time="2026-02-25T19:39:17-06:00" level=info msg="zeroconf server listening on port 33301"
Feb 25 19:39:18 volumio go-librespot[10143]: time="2026-02-25T19:39:18-06:00" level=debug msg="obtained new client token: AACRv1555c+hJRaO9F4PyZxb4UE63NCoRetbfFk7C1ilbYD7enszMgFK7CDHLYePytWab62+08qKwKy4fPkaOJ5fZoSIXBURncsQqbLpApVlsLpr/2e4gTdWyW9aAVjoKx0c8ksi4Wd3rXe1QaMwqC/Owf6evCAA5ASFCxV8lVOv+k17xtbtuf/+jzrObFR0MKbiKam9DowL2ImtvRK4vDwtirDHkSPN5D+gmYhD27xlpm8mR+LHWHw="
Feb 25 19:39:18 volumio go-librespot[10143]: time="2026-02-25T19:39:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:18 volumio go-librespot[10143]: time="2026-02-25T19:39:18-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:18 volumio go-librespot[10143]: time="2026-02-25T19:39:18-06:00" level=debug msg="completed challenge"
Feb 25 19:39:18 volumio go-librespot[10143]: time="2026-02-25T19:39:18-06: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 "
Feb 25 19:39:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:18 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:18 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56.
Feb 25 19:39:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:21 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:21 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:21 volumio go-librespot[10153]: go-librespot daemon starting...
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="app state loaded"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=info msg="zeroconf server listening on port 40171"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="obtained new client token: AACO4S44KwNxyq67knaK5AyAW4RXNTmDczg/KS4BPQEH5lBbmKLFl9lP+DDq0/gYcKWCGW2OQNJmp6LXPpWwzPAMGqRfQre7LRaDDdaufbwu8etsSWLImYe0KnbdmAYfkUFAghtpZVMpJNCZRuMCcLl08mkRF1S6hoZG368cTkjOUuhwefVco4N/U/ZKLm81UINsPmFgZPTACWfBtDDclvY66GEM9zs+nZmKuVusyAylr00oZV3CFoIUtg=="
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06:00" level=debug msg="completed challenge"
Feb 25 19:39:21 volumio go-librespot[10154]: time="2026-02-25T19:39:21-06: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 "
Feb 25 19:39:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:24 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:24 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57.
Feb 25 19:39:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:25 volumio go-librespot[10162]: go-librespot daemon starting...
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="app state loaded"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=info msg="zeroconf server listening on port 38213"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="obtained new client token: AAAFEOUphAsWp9vhQ+nu4zJqflrCzjazSxVQUKofAA0+D9uPU+TsyqV5FomYI5mp5FeiuQ/s6tu6R6fxsUYPknlZFiXL8Ew0BcgaXK2KK05PMU7s3tCnXu9JyTxN6Bg6CbtNX1fCjfc7p5ZjaY9QhExuBrbD1GVlOu7EZEWjosrK/WCWvINf5aRF92scjQLp27XFDRcK2gsm9c9maMkJa4zLl5oE61Pn4d7+dG8vgCljoqgfE5iyoCQ0rw=="
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06:00" level=debug msg="completed challenge"
Feb 25 19:39:25 volumio go-librespot[10163]: time="2026-02-25T19:39:25-06: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 "
Feb 25 19:39:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:27 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:27 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58.
Feb 25 19:39:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:29 volumio go-librespot[10184]: go-librespot daemon starting...
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="app state loaded"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=info msg="zeroconf server listening on port 44457"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="obtained new client token: AACMC6aqc6axaH2AUyVirUGYF3qIT400QI8yH4fZJqAoIIq/6LZniveBXRtcEY4x1D406EFMbc6/oBYno8zAFjhYUMplYRJyZm+sVAQF5n/yYyt38nNrcdzhDHOl6CcTh1TUmSbldlsDvvQI1IW7hEdveqvKEW3TgWbCxVI+wpXyUUPcFoHS0+n9t3oFtkiBsOaRRLOQL4PDqffkKYHdUg7CgArr9GXvP1dpGLcPcJQS6TvaMAElAZ0yCg=="
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06:00" level=debug msg="completed challenge"
Feb 25 19:39:29 volumio go-librespot[10185]: time="2026-02-25T19:39:29-06: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 "
Feb 25 19:39:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:30 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:30 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:31 volumio volumio[9528]: info: UPDATER: Scheduling automatic update
Feb 25 19:39:31 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Feb 25 19:39:31 volumio volumio[9528]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Feb 25 19:39:31 volumio volumio[9528]: info: UPDATER: Auto update will take place at: Thu Feb 26 2026 03:52:02 GMT-0600 (Central Standard Time)
Feb 25 19:39:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59.
Feb 25 19:39:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:32 volumio go-librespot[10192]: go-librespot daemon starting...
Feb 25 19:39:32 volumio go-librespot[10193]: time="2026-02-25T19:39:32-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:32 volumio go-librespot[10193]: time="2026-02-25T19:39:32-06:00" level=debug msg="app state loaded"
Feb 25 19:39:32 volumio go-librespot[10193]: time="2026-02-25T19:39:32-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:32 volumio go-librespot[10193]: time="2026-02-25T19:39:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=info msg="zeroconf server listening on port 40609"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="obtained new client token: AACc9iUIKvJnrwPy86te2Ag4/T9bF/RUeVR9J7oT++CulHSre5XrOImXXHu086yz/vL3AzLjKbsu+mm+JX9uGQXw8cswOYMOHuVXMNCE7QkN31uMbNpV/cfOIaahRZvdK79dQLC+JLpsLjJuu4e13BNvyvKDTkabsSHdYNVvWFblpEOGoFKv92CEiJFLFBBFPWautSaAS3Ol++iyIitlplqHEYM9TA1o7+y8vB5DZV28PhAw/jU++EyRew=="
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06:00" level=debug msg="completed challenge"
Feb 25 19:39:33 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:33 volumio go-librespot[10193]: time="2026-02-25T19:39:33-06: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 "
Feb 25 19:39:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:33 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: read ECONNRESET
Feb 25 19:39:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:36 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:36 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60.
Feb 25 19:39:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:36 volumio go-librespot[10200]: go-librespot daemon starting...
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=debug msg="app state loaded"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:36 volumio go-librespot[10201]: time="2026-02-25T19:39:36-06:00" level=info msg="zeroconf server listening on port 46637"
Feb 25 19:39:37 volumio go-librespot[10201]: time="2026-02-25T19:39:37-06:00" level=debug msg="obtained new client token: AAAub2u+DZxOVq8MC0yMe9MSKebMjJM7zQwVoftHdcfBehNiZLSJX/NLNr4KhSXSmOe67Znh3Tktj2O2jHmLnigWva9oD1sB1kHEvdH1Aj4rBl0Ng3HP8aEpa1ZAtR9agj5GIx6rT8vry4F0NBncAeXbUZZZjYdXUGKQVV0XGaCRDcu0QVXsUybSp1x2jU2p2wiTC/i40wj5NIlGQzQbtwm/H6ZHx49oQ0leC79s2WGfWxWowtnfBRQ="
Feb 25 19:39:37 volumio go-librespot[10201]: time="2026-02-25T19:39:37-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:37 volumio go-librespot[10201]: time="2026-02-25T19:39:37-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:37 volumio go-librespot[10201]: time="2026-02-25T19:39:37-06:00" level=debug msg="completed challenge"
Feb 25 19:39:37 volumio go-librespot[10201]: time="2026-02-25T19:39:37-06: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 "
Feb 25 19:39:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:39 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:39 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61.
Feb 25 19:39:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:40 volumio go-librespot[10223]: go-librespot daemon starting...
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="app state loaded"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=info msg="zeroconf server listening on port 33125"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="obtained new client token: AABMtIDp510CPSBg6x8jzFGjPdAJU6ixPvIRbmTq+cGxnZiNS7hmtVXudtkBnheW26v52tpCKZSYOr6YPsNabhICT313q4WpU9SfiXyrmtgxUUtnJkTgjKD1x2FvL3d0LK9zvLFfNy2i5HessbSvkUIMvWZWs0epo7xoVE3BVKzr9RONNhTDdp8YvVeAY9/+DmksBlVzE73zxMA3M7sNokAp2DDIDX2xY0bLowMEHHg4QjZsqF+35NahxA=="
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06:00" level=debug msg="completed challenge"
Feb 25 19:39:40 volumio go-librespot[10224]: time="2026-02-25T19:39:40-06: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 "
Feb 25 19:39:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:42 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:42 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62.
Feb 25 19:39:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:44 volumio go-librespot[10235]: go-librespot daemon starting...
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="app state loaded"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=info msg="zeroconf server listening on port 43005"
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="obtained new client token: AAA7ZS3flJF7VlD1TLI+j1RdQ+GMufRGgdI7u4ZCLeNU0PZMlaD25VdDbnvgaZwGxOM9aPV9ryajd8kNpmjhM4Y9TviFC5XyZ6NJ9V+MlK5vJoaV68xO7v0FKbVXjD2Pu3WM58UK1CxnvvTNQ/Cx82AnkOt1tgIx/X4+EMkJjeFx9ibRKCcMq2WZncxWx3TAFBDEE1zriqYzYvN1zGHjVUcAfOVeFS0KR74mznOjYuDUwFCm1A3AeFhgSA=="
Feb 25 19:39:44 volumio go-librespot[10236]: time="2026-02-25T19:39:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:45 volumio go-librespot[10236]: time="2026-02-25T19:39:45-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:45 volumio go-librespot[10236]: time="2026-02-25T19:39:45-06:00" level=debug msg="completed challenge"
Feb 25 19:39:45 volumio go-librespot[10236]: time="2026-02-25T19:39:45-06: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 "
Feb 25 19:39:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:45 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:45 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:48 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:48 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63.
Feb 25 19:39:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:48 volumio go-librespot[10257]: go-librespot daemon starting...
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=debug msg="app state loaded"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:48 volumio go-librespot[10258]: time="2026-02-25T19:39:48-06:00" level=info msg="zeroconf server listening on port 40677"
Feb 25 19:39:49 volumio go-librespot[10258]: time="2026-02-25T19:39:49-06:00" level=debug msg="obtained new client token: AABaNpFq6SOsoOrEuUHopul0v/MEI1lbztOeeW3Oeiw3q+wRYqgJD/gk91ncsy541lcGJy2cAL+laEVPPHbuNgYmevX00ZNpc4Hps6hJpFo+NTBaSQV9F2YUkzesFu+9qtBpO8kHZQvV5aOV8JLTEuoo1radR/jQD0MuukwoIGfQCZ6+VnB4QDoFV71DqbN0hMm7iyYskEZJPvon6AwxGyb8q99X1d6kRvpReDmzcbt6sQOO+m2VVLI="
Feb 25 19:39:49 volumio go-librespot[10258]: time="2026-02-25T19:39:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:49 volumio go-librespot[10258]: time="2026-02-25T19:39:49-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:49 volumio go-librespot[10258]: time="2026-02-25T19:39:49-06:00" level=debug msg="completed challenge"
Feb 25 19:39:49 volumio go-librespot[10258]: time="2026-02-25T19:39:49-06: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 "
Feb 25 19:39:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:51 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:51 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64.
Feb 25 19:39:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:52 volumio go-librespot[10265]: go-librespot daemon starting...
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="app state loaded"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=info msg="zeroconf server listening on port 42231"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="obtained new client token: AAAu8X4F9YQXoy0PjQgz0C7ianvD/HQytEsN5cyw6QGvudi72QabNehzSo2VWktbC+dvaFosOoYmjOjow3uXgzurQkPK2kRka+1k0FPhBCINnnf3/xHwCgLImeM6LWvCcaLwGBtWNW2g4FGWoQ9tLmZcqywz/99iB9DOFxdtM4YvtdRvPHKF3eo6oRvHBMRQARLT2Gc9Tov1CL1VYdMIPGctjkDFBeZcK7eeI54Eigvv4jG/6tWgm+8vcA=="
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06:00" level=debug msg="completed challenge"
Feb 25 19:39:52 volumio go-librespot[10266]: time="2026-02-25T19:39:52-06: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 "
Feb 25 19:39:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:54 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:54 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65.
Feb 25 19:39:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:56 volumio go-librespot[10273]: go-librespot daemon starting...
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="app state loaded"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=info msg="zeroconf server listening on port 42493"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="obtained new client token: AADOzlqx/sOG5w7ImWOMk0wTyyBK3GH7w788rwDEuxjj1QQ9moCl+aKJqebFvvkFlV/lrkHtOQ51ed8Em/ne5iXrOFKn7Q2TGY2KQXRJxJefclQ/kzhVc4RF7OONo23ou/2u1u4QVsDjOKzC2ZLQ9nNS8C5S/LnA5Q7iT6r0f1g/YGxou98386hB78saJQ3inDmYTTbrmJg/UHzleDGrojx/Uclor5xU6kTrZSfgfHmvoShj8g1AoB6a+w=="
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="completed keyexchange"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06:00" level=debug msg="completed challenge"
Feb 25 19:39:56 volumio go-librespot[10274]: time="2026-02-25T19:39:56-06: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 "
Feb 25 19:39:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:39:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:39:57 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:39:57 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:39:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66.
Feb 25 19:39:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:39:59 volumio go-librespot[10295]: go-librespot daemon starting...
Feb 25 19:39:59 volumio go-librespot[10296]: time="2026-02-25T19:39:59-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:39:59 volumio go-librespot[10296]: time="2026-02-25T19:39:59-06:00" level=debug msg="app state loaded"
Feb 25 19:39:59 volumio go-librespot[10296]: time="2026-02-25T19:39:59-06:00" level=debug msg="stored credentials not found"
Feb 25 19:39:59 volumio go-librespot[10296]: time="2026-02-25T19:39:59-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=info msg="zeroconf server listening on port 36835"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="obtained new client token: AAC8SsbE/zlKXNKZuu1cJESwi2BtqUVvaC7yjET0YndfbbC1+7W6BWdRtuoKf2aDoMzm7iol6VhzAVhPIEa0jQy4m0aQEO6hd+KxV75pXQuuf4acvPp8gZNbGcnlhYi8HmMw+ovUbW0EyZ6FInmDJ941fAdA1l7neOy06ucJUVAGx5kjtgT4tmJ04CUuvyLOdsfe8yuNPRUWVobc3AIRpgVBNIyxyT7IrkC8oU8fWkyEaenSb46vLrw8Ig=="
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="completed keyexchange"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06:00" level=debug msg="completed challenge"
Feb 25 19:40:00 volumio go-librespot[10296]: time="2026-02-25T19:40:00-06: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 "
Feb 25 19:40:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:40:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:40:00 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:40:00 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:40:03 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:40:03 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:40:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67.
Feb 25 19:40:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:40:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:40:03 volumio go-librespot[10308]: go-librespot daemon starting...
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=debug msg="app state loaded"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=debug msg="stored credentials not found"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 25 19:40:03 volumio go-librespot[10309]: time="2026-02-25T19:40:03-06:00" level=info msg="zeroconf server listening on port 40693"
Feb 25 19:40:04 volumio go-librespot[10309]: time="2026-02-25T19:40:04-06:00" level=debug msg="obtained new client token: AAAfF0WKS9Sk/OfpySiE8Xv5FbedJ/KKBE1r/43KcW2+aIu60DovYE4gvtELzid+jqTfzyPdCLPDkDCLZFpX7hxNI/L7gB5PXTIggQMa3NBK0oa/xnCijeAQLQyVeLhWtSjProPi5t+5807AGRxtswfHD4Z35PDm76mfZBss1R/XVV8pMHOGngPTmtCul6fleDIG3s/3wgaDgXqKPSP12uoyngt74zdkiAiBe0oPcyzrTDXzfwaQjPU="
Feb 25 19:40:04 volumio go-librespot[10309]: time="2026-02-25T19:40:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Feb 25 19:40:04 volumio go-librespot[10309]: time="2026-02-25T19:40:04-06:00" level=debug msg="completed keyexchange"
Feb 25 19:40:04 volumio go-librespot[10309]: time="2026-02-25T19:40:04-06:00" level=debug msg="completed challenge"
Feb 25 19:40:04 volumio go-librespot[10309]: time="2026-02-25T19:40:04-06: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 "
Feb 25 19:40:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:40:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:40:06 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:40:06 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:40:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68.
Feb 25 19:40:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:40:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:40:07 volumio go-librespot[10316]: go-librespot daemon starting...
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="app state loaded"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="stored credentials not found"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=info msg="zeroconf server listening on port 37183"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="obtained new client token: AACt0IT7CBLTfsoGbsXYTqQZ4C3+HCDDg7fjd51AwsFCqXF/tSI/BGofwKWX04FJLF0IxNrvZgbriMNAAi64Eq0svY0MV5+2uQ9hCKBi//GybTnijNA5KF8noIvuTJ96xgcI4zxBoTPTQ4zgyeCRVwLZ0rtIghZcgunAiWxT7lTKgq1eUrMdRQ6Nejrj5a0PB/MM0paDsx9pJvx9+dV6dvv+vEex4mE3/rA0eQtDB8oDfPbHMZE//XMKKQ=="
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="completed keyexchange"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06:00" level=debug msg="completed challenge"
Feb 25 19:40:07 volumio go-librespot[10317]: time="2026-02-25T19:40:07-06: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 "
Feb 25 19:40:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:40:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:40:09 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:40:09 volumio volumio[9528]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:40:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69.
Feb 25 19:40:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:40:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Feb 25 19:40:11 volumio go-librespot[10338]: go-librespot daemon starting...
Feb 25 19:40:11 volumio go-librespot[10339]: time="2026-02-25T19:40:11-06:00" level=info msg="running go-librespot 0.4.0"
Feb 25 19:40:11 volumio go-librespot[10339]: time="2026-02-25T19:40:11-06:00" level=debug msg="app state loaded"
Feb 25 19:40:11 volumio go-librespot[10339]: time="2026-02-25T19:40:11-06:00" level=debug msg="stored credentials not found"
Feb 25 19:40:11 volumio go-librespot[10339]: time="2026-02-25T19:40:11-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 25 19:40:12 volumio volumio[9528]: info: Initializing connection to go-librespot Websocket
Feb 25 19:40:12 volumio go-librespot[10339]: time="2026-02-25T19:40:12-06:00" level=debug msg="new websocket client"
Feb 25 19:40:12 volumio volumio[9528]: info: Connection to go-librespot Websocket established
Feb 25 19:40:12 volumio go-librespot[10339]: time="2026-02-25T19:40:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 25 19:40:12 volumio go-librespot[10339]: time="2026-02-25T19:40:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 25 19:40:12 volumio go-librespot[10339]: time="2026-02-25T19:40:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 25 19:40:12 volumio go-librespot[10339]: time="2026-02-25T19:40:12-06:00" level=info msg="zeroconf server listening on port 34165"
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06:00" level=debug msg="obtained new client token: AAAHiFeGDPLXQTxtzFN11N94C+5K4rTn1U77ZTSJWj047H/SXfQW6AaligoA4yrdUVyFpMZL8L9Hpx2GqYdSHDl4ddSUGOkW8JiWGue/yO//Z8C/nc64PPPzH2eVw0krUOR8m3zJ5FeMzJZAMZ5lh/S0knXNHbiBr+oUorfVwvaYCAjHlswQ+VqmZujnM8XNX0LBkLpLaxJCp8mGEab9xhV1xo8LxApJq9obucNrYlMyDC6w7gtm8Zk="
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused"
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:80"
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06:00" level=debug msg="completed keyexchange"
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06:00" level=debug msg="completed challenge"
Feb 25 19:40:13 volumio go-librespot[10339]: time="2026-02-25T19:40:13-06: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 "
Feb 25 19:40:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 25 19:40:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 25 19:40:13 volumio volumio[9528]: info: Connection to go-librespot Websocket closed
Feb 25 19:40:15 volumio volumio[9528]: info: Getting Spotify volume
Feb 25 19:40:15 volumio volumio[9528]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 25 19:40:15 volumio volumio[9528]: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 25 19:40:15 volumio volumio[9528]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Feb 25 19:40:15 volumio volumio[9528]: errno: -111,
Feb 25 19:40:15 volumio volumio[9528]: code: 'ECONNREFUSED',
Feb 25 19:40:15 volumio volumio[9528]: syscall: 'connect',
Feb 25 19:40:15 volumio volumio[9528]: address: '127.0.0.1',
Feb 25 19:40:15 volumio volumio[9528]: port: 9879,
Feb 25 19:40:15 volumio volumio[9528]: response: undefined
Feb 25 19:40:15 volumio volumio[9528]: }
Feb 25 19:40:15 volumio volumio[9528]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 25 19:40:16 volumio sudo[10360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 19:39'
Feb 25 19:40:16 volumio sudo[10360]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"