Jan 10 12:36:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 10 12:36:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:00 volumio go-librespot[4015]: go-librespot daemon starting... Jan 10 12:36:00 volumio go-librespot[4016]: time="2026-01-10T12:36:00-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:00 volumio go-librespot[4016]: time="2026-01-10T12:36:00-06:00" level=debug msg="app state loaded" Jan 10 12:36:00 volumio go-librespot[4016]: time="2026-01-10T12:36:00-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:00 volumio go-librespot[4016]: time="2026-01-10T12:36:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:00 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-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]" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-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]" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-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]" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-06:00" level=info msg="zeroconf server listening on port 40201" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-06:00" level=debug msg="obtained new client token: AABsUQoWwPoIzbRDvxbxkLGV2Vx4hqzgsM57O/Bsg+9hNGMUtIKD5SROqYQ7EsuIo5AkDuHWKCbKyui81QlwyPlh7uqvBGixUFQBIu5h7tbFewPK/stO+iydYv4TyhQm4+tcDswbsO63zRqnCwjNlDrMa5gBjxLRTtda3/3Ubu5BN5bM9JWRVHUJXDS3mRcUW8zDQmynrdN9et1tYY2uOPa82gRt0EDtu3nxA2rR6mYuA+jW+0J/eVq34Q==" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-06:00" level=debug msg="completed challenge" Jan 10 12:36:01 volumio go-librespot[4016]: time="2026-01-10T12:36:01-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 " Jan 10 12:36:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:02 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.11.147 Jan 10 12:36:02 volumio avahi-daemon[664]: Registering new address record for 169.254.11.147 on eth0.IPv4. Jan 10 12:36:02 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.11.147 Jan 10 12:36:03 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.11.147 Jan 10 12:36:03 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.11.147 Jan 10 12:36:03 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:03 volumio avahi-daemon[664]: Withdrawing address record for 169.254.11.147 on eth0. Jan 10 12:36:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:03 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:04 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: socket hang up Jan 10 12:36:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jan 10 12:36:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:05 volumio go-librespot[4057]: go-librespot daemon starting... Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=debug msg="app state loaded" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-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]" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-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]" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-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]" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=info msg="zeroconf server listening on port 37345" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=debug msg="obtained new client token: AADRLMvX6WEdERr8278jjtwzvfq71bSw34L/xbybgCiAixTyTHYNJUIGAD2avs8a6IsXUWpb1dsYhmQk8pW3kbfPeRFx0rDDby1bdIdBmy5F+DuDZXvzkEexqzBTkekyb7NX9zdn4z09soyimft22Gr3C7pEF+qm8iRE560Yp+ye/BuW4Pe9NvT574GGdGtGrviSdrZG1opLEFo5gxbpp3epziTma2YzHkCUy5fCOaGDEN9raGGpatX2dQ==" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-06:00" level=debug msg="completed challenge" Jan 10 12:36:05 volumio go-librespot[4058]: time="2026-01-10T12:36:05-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 " Jan 10 12:36:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin bluetooth to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin multiroom to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin metavolumio to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin cd_controller to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 10 12:36:05 volumio volumio[3319]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 10 12:36:07 volumio volumio[3319]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 10 12:36:07 volumio volumio[3319]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 10 12:36:07 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:07 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:07 volumio volumio[3319]: info: Starting MyVolumio Remote Streaming Endpoints Jan 10 12:36:07 volumio volumio[3319]: info: MyVolumio login type: Token Jan 10 12:36:07 volumio volumio[3319]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 10 12:36:07 volumio volumio[3319]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 10 12:36:07 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.32.173 Jan 10 12:36:07 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:07 volumio avahi-daemon[664]: Registering new address record for 169.254.32.173 on eth0.IPv4. Jan 10 12:36:07 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:07 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:07 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.32.173 Jan 10 12:36:08 volumio volumio[3319]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 10 12:36:08 volumio volumio[3319]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 10 12:36:08 volumio volumio[3319]: info: Streaming services startup Jan 10 12:36:08 volumio volumio[3319]: info: Starting Streaming Daemon Jan 10 12:36:08 volumio sudo[4083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 10 12:36:08 volumio sudo[4083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 12:36:08 volumio volumio[3319]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 10 12:36:08 volumio sudo[4083]: pam_unix(sudo:session): session closed for user root Jan 10 12:36:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.32.173 Jan 10 12:36:08 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.32.173 Jan 10 12:36:08 volumio avahi-daemon[664]: Withdrawing address record for 169.254.32.173 on eth0. Jan 10 12:36:08 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:08 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:08 volumio volumio[3319]: error: Cannot start Volumio Streaming Daemon Jan 10 12:36:08 volumio volumio[3319]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 10 12:36:08 volumio volumio[3319]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 10 12:36:08 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:08 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jan 10 12:36:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:08 volumio volumio[3319]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 10 12:36:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:09 volumio go-librespot[4120]: go-librespot daemon starting... Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=debug msg="app state loaded" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-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]" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-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]" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-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]" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=info msg="zeroconf server listening on port 43401" Jan 10 12:36:09 volumio volumio[3319]: info: MyVolumio token set successfully Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=debug msg="obtained new client token: AABNf0/iLAK4TE55BUG25s0ul46MsZ8SkQX0J4mwoxB6S/ncqZvjcXAp/JkuUrtlVuAJU8pD3090q9tTqNaNMdBEPdDncRVZHqfXpd8ivI0FSxhjbTDEyLYPZlZ92o2nLioofvMLwVCqhlwlfjBo/V8EIEJTI+WX4CnY94z6LE3M5bQi//5NXp1PmatWEOvXWuPcFUNtXOyFDraBIuDV/O64SIdv5YhGTrRIgrQht0lP0egV3GgbmoC4rA==" Jan 10 12:36:09 volumio volumio[3319]: info: MYVOLUMIO: Adding device Jan 10 12:36:09 volumio volumio[3319]: info: MYVOLUMIO: Evaluating Server Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-06:00" level=debug msg="completed challenge" Jan 10 12:36:09 volumio go-librespot[4121]: time="2026-01-10T12:36:09-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 " Jan 10 12:36:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:09 volumio volumio[3319]: info: MyVolumio status changed Jan 10 12:36:09 volumio volumio[3319]: info: Streaming services startup Jan 10 12:36:09 volumio volumio[3319]: info: Starting Streaming Daemon Jan 10 12:36:09 volumio volumio[3319]: info: Removing browser output: myVolumio user plan is not superstar Jan 10 12:36:09 volumio volumio[3319]: info: Removing audio output: Jan 10 12:36:09 volumio volumio[3319]: info: Stoppping Tunnel 1 Jan 10 12:36:09 volumio sudo[4150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 10 12:36:09 volumio sudo[4150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 12:36:09 volumio sudo[4152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 10 12:36:09 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 12:36:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 10 12:36:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 10 12:36:09 volumio sudo[4150]: pam_unix(sudo:session): session closed for user root Jan 10 12:36:09 volumio volumio[3319]: error: Cannot start Volumio Streaming Daemon Jan 10 12:36:09 volumio volumio[3319]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 10 12:36:09 volumio volumio[3319]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 10 12:36:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 10 12:36:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 10 12:36:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 10 12:36:09 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 10 12:36:09 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root Jan 10 12:36:09 volumio volumio[3319]: info: Remote SSH Stopped Jan 10 12:36:09 volumio volumio[3319]: info: Setting Geolocation for MyVolumio to us1 Jan 10 12:36:09 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:09 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:09 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:09 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 12:36:10 volumio volumio[3319]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 10 12:36:10 volumio volumio[3319]: info: Updating MyVolumio device info Jan 10 12:36:10 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:10 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:10 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:11 volumio volumio[3319]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 10 12:36:11 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:11 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jan 10 12:36:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:12 volumio go-librespot[4156]: go-librespot daemon starting... Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-06:00" level=debug msg="app state loaded" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-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]" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-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]" Jan 10 12:36:12 volumio go-librespot[4157]: time="2026-01-10T12:36:12-06:00" level=info msg="zeroconf server listening on port 36973" Jan 10 12:36:13 volumio go-librespot[4157]: time="2026-01-10T12:36:13-06:00" level=debug msg="obtained new client token: AABFvenCqa/i4bxhNFlEwoIQPkxIsdZQOjjKbrIhz2jB3tkZnIt9zoGllcy+MNzz0i/7Y+bOfKUN7mb7TVrwQF1LOXrmAg9QppuQ0O6VqlTYCvvVR0mhI4JtCaKz5RzsdwcUWx0sAq8nt2b5aBfBoX/9xKA+0+BLNrVsVeSaM0wIIN7DiL+Cw0Yzs+vYVjdLq7rVIuGTsPXne+ZmrDs88vlSJTU7qEzL6MknKl7WJf8p97TlYI9uWt0=" Jan 10 12:36:13 volumio go-librespot[4157]: time="2026-01-10T12:36:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:13 volumio go-librespot[4157]: time="2026-01-10T12:36:13-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:13 volumio go-librespot[4157]: time="2026-01-10T12:36:13-06:00" level=debug msg="completed challenge" Jan 10 12:36:13 volumio go-librespot[4157]: time="2026-01-10T12:36: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 " Jan 10 12:36:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.227.194 Jan 10 12:36:13 volumio avahi-daemon[664]: Registering new address record for 169.254.227.194 on eth0.IPv4. Jan 10 12:36:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.227.194 Jan 10 12:36:14 volumio volumio[3319]: info: MYVOLUMIO: Adding device Jan 10 12:36:14 volumio volumio[3319]: info: MYVOLUMIO: Evaluating Server Jan 10 12:36:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.227.194 Jan 10 12:36:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.227.194 Jan 10 12:36:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.227.194 on eth0. Jan 10 12:36:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:14 volumio volumio[3319]: info: Setting Geolocation for MyVolumio to us4 Jan 10 12:36:14 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:14 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:14 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:14 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:14 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:15 volumio volumio[3319]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 10 12:36:15 volumio volumio[3319]: info: Updating MyVolumio device info Jan 10 12:36:15 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:15 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:15 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:16 volumio volumio[3319]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 10 12:36:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jan 10 12:36:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:16 volumio go-librespot[4214]: go-librespot daemon starting... Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=debug msg="app state loaded" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-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]" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-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]" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-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]" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=info msg="zeroconf server listening on port 38761" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=debug msg="obtained new client token: AAAJ9pkGbCoFX2p3ogdzAipfFHwfL1EyuPzpaqqoBh3bwZmYJG5393UwybcHOASmYd+REYkoFXckuvT/Oc6OqFjzMfDEnu6sS1BBf0FYhic2Qv1+CAV6Inwkw67EMv16TNjoehG+nof+Yqy/sw64+wnAuKzHsnFwPUsLzU+itKPrlI1TSzO5flZnijPYkOpV13aTr6vWthFvQKzxvDAAnMJ4UqpI/PFW/2/32xew9uq1b2y72Gned/G4fQ==" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-06:00" level=debug msg="completed challenge" Jan 10 12:36:16 volumio go-librespot[4215]: time="2026-01-10T12:36:16-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 " Jan 10 12:36:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:17 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:17 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:17 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 12:36:17 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:17 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 12:36:17 volumio volumio-remote-updater[14193]: Test mode disabled Jan 10 12:36:17 volumio volumio-remote-updater[14193]: Alpha mode disabled Jan 10 12:36:17 volumio volumio-remote-updater[14193]: Alpha legacy test mode disabled Jan 10 12:36:17 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 10 12:36:18 volumio volumio[3319]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

ANNOUNCEMENT

\n
    \n
  • Production line
    • \n
\n

FIX

\n
    \n
  • Nodejs 20.5.1 version pinning with static package fallback
    • \n
\n","title":"Update v4.073","updateavailable":true} Jan 10 12:36:18 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 10 12:36:19 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.209.139 Jan 10 12:36:19 volumio avahi-daemon[664]: Registering new address record for 169.254.209.139 on eth0.IPv4. Jan 10 12:36:19 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.209.139 Jan 10 12:36:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.209.139 Jan 10 12:36:19 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.209.139 Jan 10 12:36:19 volumio avahi-daemon[664]: Withdrawing address record for 169.254.209.139 on eth0. Jan 10 12:36:19 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:20 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Jan 10 12:36:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:20 volumio go-librespot[4275]: go-librespot daemon starting... Jan 10 12:36:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=debug msg="app state loaded" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-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]" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-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]" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-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]" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=info msg="zeroconf server listening on port 38903" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=debug msg="obtained new client token: AACb1bJYBh1aCrHosPWIp8s+g5mOif2tRZrxxiiKVZ8QIPYqAKhzJMKX8BkntMHmLjBl475Q4hS4UqCMx6y6x28q3qZb6hCROGF3b+DQ//n5V1NBldoAHkGFUuqGptny0WLJUTon+987gy9w+Aw6LbXSZ5MKRV/6xHcNdgPkIJVPk73dSCqFiiG0TxwGpS5R3WTbhovvFQ7b3UVjMBirmjlNmoQ8qm4AZLaQslNdWEpJqRjcU2EL7eTfWQ==" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-06:00" level=debug msg="completed challenge" Jan 10 12:36:20 volumio go-librespot[4276]: time="2026-01-10T12:36:20-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 " Jan 10 12:36:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:20 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:20 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:22 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 12:36:22 volumio volumio[3319]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 10 12:36:22 volumio volumio[3319]: info: CoreCommandRouter::volumioGetState Jan 10 12:36:22 volumio volumio[3319]: info: CorePlayQueue::getTrack 0 Jan 10 12:36:23 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:23 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Jan 10 12:36:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:23 volumio go-librespot[4286]: go-librespot daemon starting... Jan 10 12:36:23 volumio go-librespot[4287]: time="2026-01-10T12:36:23-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:23 volumio go-librespot[4287]: time="2026-01-10T12:36:23-06:00" level=debug msg="app state loaded" Jan 10 12:36:23 volumio go-librespot[4287]: time="2026-01-10T12:36:23-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:23 volumio go-librespot[4287]: time="2026-01-10T12:36:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-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]" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-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]" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-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]" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-06:00" level=info msg="zeroconf server listening on port 35483" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-06:00" level=debug msg="obtained new client token: AACAa0EyYH4EpAIT8etJSFE2QJl1mDoGhtjMvAXcDLnMwAk71qh4+dZxF9x0dH6eRjNIu/3VuzglJb6eEQPE4rmOOGuBaZmBt7OSp6Ua+SFicd6Wg4bWUmITFTxWsUi3ot4v92uSAwdtwmvPvtCMppCj6mWvQi4Lg0L8twSLio/U1V11TQmnUuA88CfzcGFrkRKZ6AqtMcFYkrOtPs4Kw9SZCCys1Yc0Wu98A4qKl6fSdn5u90fOlbE=" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-06:00" level=debug msg="completed challenge" Jan 10 12:36:24 volumio go-librespot[4287]: time="2026-01-10T12:36:24-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 " Jan 10 12:36:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:25 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.93.148 Jan 10 12:36:25 volumio avahi-daemon[664]: Registering new address record for 169.254.93.148 on eth0.IPv4. Jan 10 12:36:25 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.93.148 Jan 10 12:36:26 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:26 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:26 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.93.148 Jan 10 12:36:26 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.93.148 Jan 10 12:36:26 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:26 volumio avahi-daemon[664]: Withdrawing address record for 169.254.93.148 on eth0. Jan 10 12:36:26 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:26 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:26 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:26 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Jan 10 12:36:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:27 volumio go-librespot[4327]: go-librespot daemon starting... Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=debug msg="app state loaded" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 12:36:27 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-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]" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-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]" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-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]" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=info msg="zeroconf server listening on port 39953" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=debug msg="obtained new client token: AACv9wAUm3eTbdVXgdvTimJ2tP86fRPVgVP5JEdc/SmUbVJ4/41u5Y8dcWyCN9okgZkt9aA8AWEz18aCfU5Q9MUt2Tz3ncDnNaA5Ul8dmAvhRtnCDKQNJwxhVgICAJ5YdVpDA4+fsWti77RYW9FxJGfxICWReisOWpDq+4iLkAqDzVgj4+j4ZPQAOHdXyQlUfw1Z+k/+M3trjfkEo6JimCu5VtsoByq0joWQeLhUlV9FnSWuakZ5ebrLmA==" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:27 volumio go-librespot[4328]: time="2026-01-10T12:36:27-06:00" level=debug msg="completed challenge" Jan 10 12:36:28 volumio go-librespot[4328]: time="2026-01-10T12:36:28-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 " Jan 10 12:36:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:28 volumio volumio[3319]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 10 12:36:28 volumio volumio[3319]: info: Completed starting MyVolumio Plugin Jan 10 12:36:28 volumio volumio[3319]: [Metrics] CommandRouter: 62s 33.53ms Jan 10 12:36:28 volumio volumio[3319]: info: CoreCommandRouter::volumiosetStartupVolume Jan 10 12:36:28 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 12:36:28 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 12:36:28 volumio volumio[3319]: info: CoreCommandRouter::Close All Modals sent Jan 10 12:36:28 volumio volumio[3319]: info: CoreCommandRouter::Close All Modals sent Jan 10 12:36:29 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 10 12:36:29 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 12:36:29 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 10 12:36:29 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:29 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:30 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 12:36:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Jan 10 12:36:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:31 volumio go-librespot[4356]: go-librespot daemon starting... Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=debug msg="app state loaded" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-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]" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-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]" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-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]" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=info msg="zeroconf server listening on port 35739" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=debug msg="obtained new client token: AAA1qodFin4gOw3lLj7rwAm7CkaKn1l7EXcUiWsGAs2k9t8MpBb0Ei2FZHXlzmY+3S0SgqypkCLDMGvYVjGKnKSTxQqUo4fIhN4vY3JI8YmQZWEfw1Bc+z2gslD32q9Vtb/MgVGbqCKg/GBW4KermQcTmMjht6FXX0/DahfFZOP7o2Zk6B6PN8/+Bv+j1YdpRjnqiQbkCkBwQpXXmGE6eGHct/W81x3rr4J4l6d/1XSSa3wrLwm2ujilMQ==" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:31 volumio go-librespot[4357]: time="2026-01-10T12:36:31-06:00" level=debug msg="completed challenge" Jan 10 12:36:32 volumio go-librespot[4357]: time="2026-01-10T12:36:32-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 " Jan 10 12:36:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:32 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.7.149 Jan 10 12:36:32 volumio avahi-daemon[664]: Registering new address record for 169.254.7.149 on eth0.IPv4. Jan 10 12:36:32 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:32 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:32 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:32 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.7.149 Jan 10 12:36:33 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.7.149 Jan 10 12:36:33 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.7.149 Jan 10 12:36:33 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:33 volumio avahi-daemon[664]: Withdrawing address record for 169.254.7.149 on eth0. Jan 10 12:36:33 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:33 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:33 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:33 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:34 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 12:36:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Jan 10 12:36:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:35 volumio go-librespot[4398]: go-librespot daemon starting... Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=debug msg="app state loaded" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-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]" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-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]" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-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]" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=info msg="zeroconf server listening on port 36647" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=debug msg="obtained new client token: AADSqHHxEJBS0xU9vzuQNi9ZvIhZ3k7BSp47/2Ag58jhD3fZ2Rp+PYX2MFxyTP9jfKZKSNN8RC/kiQl8mlEHI1JYEV3jbQnMB08ZsQUBgIl27EGL6v6QTyptq1mBVPozR2Hq5SqRxA1ckbiXlA0S/CZxdPlUbG1zHTT4K5/lJhIzH8m2n9G93ROQsPp2xZ9+BQ+eQosxtbYoLVL6tRsi1Y4BdpX3UXl0cOr5gpAgsUw2sLVVtXWsRBhvpw==" Jan 10 12:36:35 volumio volumio[3319]: info: BOOT COMPLETED Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-06:00" level=debug msg="completed challenge" Jan 10 12:36:35 volumio go-librespot[4399]: time="2026-01-10T12:36:35-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 " Jan 10 12:36:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:35 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:35 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:38 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.66.188 Jan 10 12:36:38 volumio avahi-daemon[664]: Registering new address record for 169.254.66.188 on eth0.IPv4. Jan 10 12:36:38 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:38 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:38 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:38 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:38 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.66.188 Jan 10 12:36:38 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:38 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:38 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.66.188 Jan 10 12:36:38 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.66.188 Jan 10 12:36:38 volumio avahi-daemon[664]: Withdrawing address record for 169.254.66.188 on eth0. Jan 10 12:36:38 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:38 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Jan 10 12:36:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:38 volumio go-librespot[4441]: go-librespot daemon starting... Jan 10 12:36:39 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:39 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:39 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=debug msg="app state loaded" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-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]" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-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]" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-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]" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=info msg="zeroconf server listening on port 33069" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=debug msg="obtained new client token: AAC5PHO3M0L23MAd2PJRp2VBwEgUibKIHeimOH9mfm8GKdUk+PMxN0L7bvUh3+LSfxCW5X8w5cm81qaARfWWVt7diW8lFRvr8oheZ73yv4uwpRlbp8aQD2dBk2AwSwsqZwPH+KtuTz18VYwZbNhofUK3ufcHvL8/g6P3OhP0QkNkD7SY79Yyw5XFc09G+uxYZfncF3rYfSeXYXcUls+j0OCeh0pqoR4zqjmCKNg9ndc3ty8LG1LkNq55dw==" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-06:00" level=debug msg="completed challenge" Jan 10 12:36:39 volumio go-librespot[4446]: time="2026-01-10T12:36:39-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 " Jan 10 12:36:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:41 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:41 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Jan 10 12:36:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:42 volumio go-librespot[4464]: go-librespot daemon starting... Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-06:00" level=debug msg="app state loaded" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-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]" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-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]" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-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]" Jan 10 12:36:42 volumio go-librespot[4465]: time="2026-01-10T12:36:42-06:00" level=info msg="zeroconf server listening on port 42961" Jan 10 12:36:43 volumio go-librespot[4465]: time="2026-01-10T12:36:43-06:00" level=debug msg="obtained new client token: AABAbbzssZFeVUqiX3Gp/QI7viU/kZFHOKxiXKg5ewTE3Sjs2V/8qqcBnggEMfEdRnaHG0QsbCjJP6ZSsdR/kfc3jQP95FCUdFk7E+qJPEcax/yo7uLdSOJ9dLz+vVVPp96nbAUeMSW+z2mw7WP9ujPVAp1f9HHnkVuCrjEob1Ub0UgOJS5ji0gUAvr71N/GVuiyDuwkjt2wOaWfQ/I3F+LLkUuGjBN/t0mT4RZXsh/De9Z7NI0iHHw=" Jan 10 12:36:43 volumio go-librespot[4465]: time="2026-01-10T12:36:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:43 volumio go-librespot[4465]: time="2026-01-10T12:36:43-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:43 volumio go-librespot[4465]: time="2026-01-10T12:36:43-06:00" level=debug msg="completed challenge" Jan 10 12:36:43 volumio go-librespot[4465]: time="2026-01-10T12:36:43-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 " Jan 10 12:36:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:43 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.252.107 Jan 10 12:36:43 volumio avahi-daemon[664]: Registering new address record for 169.254.252.107 on eth0.IPv4. Jan 10 12:36:43 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:43 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:43 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:43 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:43 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.252.107 Jan 10 12:36:44 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.252.107 Jan 10 12:36:44 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.252.107 Jan 10 12:36:44 volumio avahi-daemon[664]: Withdrawing address record for 169.254.252.107 on eth0. Jan 10 12:36:44 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:44 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:44 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:44 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:44 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:44 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:44 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Jan 10 12:36:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:46 volumio go-librespot[4505]: go-librespot daemon starting... Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=debug msg="app state loaded" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-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]" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-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]" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-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]" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=info msg="zeroconf server listening on port 37517" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=debug msg="obtained new client token: AAA8ZxSve24uFIsi+8nwFPQ7ir5jDlNoieaRzFGJ9Y6xP10yMZ4HKT2zOC7VKx/GDGVERRY1V0KXkjcjP/NX3QNq1MD4FoBe+Ww+ew9BZ16yAhWQOPJe6YYWOgNet8ukoh1c5h6RUZ7xH2f3DGs1VCa3WuNYjq04YPqZHQg6bQrkGGbrfbySRoMa674UjGr/cbFtxN0LY17uENG3viDIQD9w8AITa1SOfa3QTTA5M1qwEZuhl51jkCVgig==" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:46 volumio go-librespot[4506]: time="2026-01-10T12:36:46-06:00" level=debug msg="completed challenge" Jan 10 12:36:47 volumio go-librespot[4506]: time="2026-01-10T12:36:47-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 " Jan 10 12:36:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:47 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:47 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:47 volumio volumio[3319]: info: UPDATER: Scheduling automatic update Jan 10 12:36:47 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Jan 10 12:36:47 volumio volumio[3319]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Jan 10 12:36:47 volumio volumio[3319]: info: UPDATER: Auto update will take place at: Sun Jan 11 2026 03:24:15 GMT-0600 (Central Standard Time) Jan 10 12:36:49 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.161.136 Jan 10 12:36:49 volumio avahi-daemon[664]: Registering new address record for 169.254.161.136 on eth0.IPv4. Jan 10 12:36:49 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:49 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:49 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:49 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:50 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.161.136 Jan 10 12:36:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Jan 10 12:36:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:50 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.161.136 Jan 10 12:36:50 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.161.136 Jan 10 12:36:50 volumio avahi-daemon[664]: Withdrawing address record for 169.254.161.136 on eth0. Jan 10 12:36:50 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:50 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:50 volumio go-librespot[4546]: go-librespot daemon starting... Jan 10 12:36:50 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:50 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:50 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=debug msg="app state loaded" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-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]" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-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]" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-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]" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=info msg="zeroconf server listening on port 37183" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=debug msg="obtained new client token: AADNy1XWgoN2uCtS02DCVknIlYGRxNwHfTaIz65BOzxaexuQTvJX/TloRoQRrAeuLOLLsTOJ0qkql1hndoDbxtTZ1XH5IzPvgBWUCq0AUJc2bLjS/7fSiqWPIDDGa4gvixMegm0Kp0PMJWYFpMUnEVUnqnl03MELkUi2GVD28Bkio61HdpGzBWBfloJZRigUFBJe2dJKlBeZv1iHZ9DDM7thoX8nNHX3H2kYTmqeehrUHHRQEuqBoFy7BQ==" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-06:00" level=debug msg="completed challenge" Jan 10 12:36:50 volumio go-librespot[4561]: time="2026-01-10T12:36:50-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 " Jan 10 12:36:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:50 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:50 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:53 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:53 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Jan 10 12:36:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:53 volumio go-librespot[4569]: go-librespot daemon starting... Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=debug msg="app state loaded" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-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]" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-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]" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-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]" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=info msg="zeroconf server listening on port 36679" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=debug msg="obtained new client token: AACn0339tYCXIIFb3ZZyuMULGYCeFg5dLiJ1a/NfwSQJ4KIVIPLpKz/IdSGv0IqmyYN8kFApEbGQHbzqr4i2N7tAXDhcR2NieImf9zFv017aInoQ7UENxSkkom7QXeaOjeTIScZMAf2TwllCsKvbGFsWL678IT/bJ3gxczqJxr0ddl7AC79YVVdmBn3toNS1ES8tGGPR+sVUlTpx/lvNcHKjJORaSiZ/qjg2ZDbE48gwTPAVPfoKh6TgXw==" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-06:00" level=debug msg="completed challenge" Jan 10 12:36:54 volumio go-librespot[4570]: time="2026-01-10T12:36:54-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 " Jan 10 12:36:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:55 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.170.32 Jan 10 12:36:55 volumio avahi-daemon[664]: Registering new address record for 169.254.170.32 on eth0.IPv4. Jan 10 12:36:55 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:36:55 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:55 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:55 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.170.32 Jan 10 12:36:55 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:56 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.170.32 Jan 10 12:36:56 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.170.32 Jan 10 12:36:56 volumio avahi-daemon[664]: Withdrawing address record for 169.254.170.32 on eth0. Jan 10 12:36:56 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:36:56 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:36:56 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:56 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:36:56 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:36:56 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:56 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:36:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Jan 10 12:36:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:36:57 volumio go-librespot[4609]: go-librespot daemon starting... Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-06:00" level=debug msg="app state loaded" Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-06:00" level=debug msg="stored credentials not found" Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-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]" Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-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]" Jan 10 12:36:57 volumio go-librespot[4610]: time="2026-01-10T12:36:57-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]" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-06:00" level=info msg="zeroconf server listening on port 45303" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-06:00" level=debug msg="obtained new client token: AAB+e6aIjqyMQG07/CRY2alAQdHfj8ukCJwHie9M8NuzuS4P0Ym/LsoGEMMRvSnsRaqE+Qu4ywLSPR1uswnja8jqn6sh4NlX111iX5GrtugIX6f7u/ySrSd/EEdHMB9CGaY1T91OqqLTcCns2i+UPV14CgrDpi+I/DKJhuBuUWZMFrzjo4ldbfZR7XLF7vITeVR8/jq9OEHCdvFuu/NQckE9L+sdOJ/7Kjx94x9OB9TsByHTXE0zTS8=" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-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" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-06:00" level=debug msg="completed keyexchange" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-06:00" level=debug msg="completed challenge" Jan 10 12:36:58 volumio go-librespot[4610]: time="2026-01-10T12:36:58-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 " Jan 10 12:36:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:36:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:36:59 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:36:59 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Jan 10 12:37:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:01 volumio go-librespot[4634]: go-librespot daemon starting... Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=debug msg="app state loaded" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:01 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.168.33 Jan 10 12:37:01 volumio avahi-daemon[664]: Registering new address record for 169.254.168.33 on eth0.IPv4. Jan 10 12:37:01 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-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]" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-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]" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-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]" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=info msg="zeroconf server listening on port 43695" Jan 10 12:37:01 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:01 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:01 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:01 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.168.33 Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=debug msg="obtained new client token: AABece10nNLrJDmCT2/9FYOkVy+vcS522GxJXicDEY2S/ye0jwHKdvqO3s3WiEP1qTNFt25Atsw2yvmuHO1oTg4TcpPVMo1jKIk9fxrqzeXVtkJCPI3QzUS0XlrUhGHGD5vQCsScu6+TV2pqdSQ7t3OWqZu//+kCnTsMyJBWDAQxysaBmmj95lJlFTPB3UcDcnCVTpAFMAcpd2vy5TKRBaultadHp/orqfWRsmvUukxKsbPkEasg3RA9xQ==" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:01 volumio go-librespot[4635]: time="2026-01-10T12:37:01-06:00" level=debug msg="completed challenge" Jan 10 12:37:02 volumio go-librespot[4635]: time="2026-01-10T12:37:02-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 " Jan 10 12:37:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.168.33 Jan 10 12:37:02 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.168.33 Jan 10 12:37:02 volumio avahi-daemon[664]: Withdrawing address record for 169.254.168.33 on eth0. Jan 10 12:37:02 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:37:02 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:37:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:02 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:02 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Jan 10 12:37:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:05 volumio go-librespot[4674]: go-librespot daemon starting... Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=debug msg="app state loaded" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-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]" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-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]" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-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]" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=info msg="zeroconf server listening on port 34231" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=debug msg="obtained new client token: AAAb5KMoMOoK3/RCXWyJeJtkrABCH0kyYXngRkCApmPGnhRAllQCfG0Ln0WRRkBf5j1/9fCDoNA3fP+ayWsO6oVPBdfXIP39pKB949BSsNZ+kZihWZUPXyAArzjBzatgmtWKhPY84uLxVMf92XXoiNrJbQh1C9dqHKjdY4UlTfwoZBtI2glovrJKqwjec1ZUV80N7X9EAJJMXyBr1EvRVpnvXgcgT79nlvTEv5PwHz+hfC0xukTbfi6Few==" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-06:00" level=debug msg="completed challenge" Jan 10 12:37:05 volumio go-librespot[4675]: time="2026-01-10T12:37:05-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 " Jan 10 12:37:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:05 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:05 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:07 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.35.220 Jan 10 12:37:07 volumio avahi-daemon[664]: Registering new address record for 169.254.35.220 on eth0.IPv4. Jan 10 12:37:07 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:37:07 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:07 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:07 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.35.220 Jan 10 12:37:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.35.220 Jan 10 12:37:08 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.35.220 Jan 10 12:37:08 volumio avahi-daemon[664]: Withdrawing address record for 169.254.35.220 on eth0. Jan 10 12:37:08 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:37:08 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:37:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:08 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:08 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Jan 10 12:37:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:08 volumio go-librespot[4715]: go-librespot daemon starting... Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=debug msg="app state loaded" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-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]" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-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]" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-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]" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=info msg="zeroconf server listening on port 36323" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=debug msg="obtained new client token: AAAUR+bCyrfhUMgF8Y9Y4SJhgctdvSn6IJSHMa9dDZScv2V3p0N3fjjZXJgfCCqBRjvAjprIExoBqRZJsXTrdbjEr4fx8bSR62RNhguqdfz7AIKBTQddCtsl+VLP69Pojk0VXdqIX0/kpb5EmcUSoGvbAY/Wugr3044gzqhUFNdwQCbba66Sole5gWXKpi/ctQsoff24jFCIX+RsyuFOVPOzDG2y6KOalbYhN5HmSEFOy55jBjcP267+vA==" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-06:00" level=debug msg="completed challenge" Jan 10 12:37:09 volumio go-librespot[4716]: time="2026-01-10T12:37:09-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 " Jan 10 12:37:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:11 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:11 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Jan 10 12:37:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:12 volumio go-librespot[4737]: go-librespot daemon starting... Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37:12-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37:12-06:00" level=debug msg="app state loaded" Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37:12-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37:12-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]" Jan 10 12:37:12 volumio go-librespot[4738]: time="2026-01-10T12:37:12-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]" Jan 10 12:37:13 volumio go-librespot[4738]: time="2026-01-10T12:37:13-06:00" level=info msg="zeroconf server listening on port 44763" Jan 10 12:37:13 volumio go-librespot[4738]: time="2026-01-10T12:37:13-06:00" level=debug msg="obtained new client token: AAAplCu06yqDcVj+fqZocdJhjQP526uk78ctxV3JkBGu/f53m400uT01f+Y7Tk9eDUzZT8kVD0GIaQqWiam3JMYc03Dga1c5oQ6Otcnr9WQFyHPwOaXMWrvq/mLswKdBhHoGP4m5w0Ix6Hcfu0UozRMBYbLIe4stxcRx/pRsu+2BLW5rG148VM7A3WY2QL8B9tcehl6m+X3eXa8G76yrmIf94s55H0p8hjFGJT5IPZNc2rPLTTtbvIQ=" Jan 10 12:37:13 volumio go-librespot[4738]: time="2026-01-10T12:37:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:13 volumio go-librespot[4738]: time="2026-01-10T12:37:13-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:13 volumio go-librespot[4738]: time="2026-01-10T12:37:13-06:00" level=debug msg="completed challenge" Jan 10 12:37:13 volumio go-librespot[4738]: time="2026-01-10T12:37: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 " Jan 10 12:37:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.69.82 Jan 10 12:37:13 volumio avahi-daemon[664]: Registering new address record for 169.254.69.82 on eth0.IPv4. Jan 10 12:37:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:37:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.69.82 Jan 10 12:37:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.69.82 Jan 10 12:37:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.69.82 Jan 10 12:37:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:37:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.69.82 on eth0. Jan 10 12:37:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:37:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:14 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:14 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Jan 10 12:37:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:16 volumio go-librespot[4777]: go-librespot daemon starting... Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=debug msg="app state loaded" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-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]" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-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]" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-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]" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=info msg="zeroconf server listening on port 35505" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=debug msg="obtained new client token: AACpgPedKJdhDYcxeXeB2ECviLWH4XOSDAfJdngVJ1x3fNqP6UUukSE/2NRQSHgh7hpBy65OANxmDqpP0pfhjnEG0IU5YDou8rzGMWuH0hKUEx4+gS8/XVHfchbBcz07pv4Rwyv4TaS5K72v4cyw36Gl/H+3Il2i1WFZ4z3gem+pLfcMVzW8pQ4/LJkOd7uEN2PkWm71k9JoU3M1ItoulKlvh52y8+X5JaCh6FjkjU71hZw2DhC7REyiGg==" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-06:00" level=debug msg="completed challenge" Jan 10 12:37:16 volumio go-librespot[4778]: time="2026-01-10T12:37:16-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 " Jan 10 12:37:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:17 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:17 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:19 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.29.247 Jan 10 12:37:19 volumio avahi-daemon[664]: Registering new address record for 169.254.29.247 on eth0.IPv4. Jan 10 12:37:19 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:37:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.29.247 Jan 10 12:37:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Jan 10 12:37:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:20 volumio go-librespot[4819]: go-librespot daemon starting... Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=debug msg="app state loaded" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:20 volumio ntpd[872]: IO: Listen normally on 11604 eth0 169.254.29.247:123 Jan 10 12:37:20 volumio ntpd[872]: IO: new interface(s) found: waking up resolver Jan 10 12:37:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.29.247 Jan 10 12:37:20 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.29.247 Jan 10 12:37:20 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:37:20 volumio avahi-daemon[664]: Withdrawing address record for 169.254.29.247 on eth0. Jan 10 12:37:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:20 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-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]" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-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]" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-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]" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=info msg="zeroconf server listening on port 39267" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=debug msg="obtained new client token: AAAuOTbjO9BeijqjtLrcBc8E/aPFojt5cJw77QPSeRlZp0tnpYjrm2Q6KzNn7gV7dpywXvU39i0QJsy63TKHSZotgYrXZMUo3SZnV55+fKRdmtuf+6COMVaafCP/Tx5O5ovBZJbyWgRzZPFSrsqCHE0x5xx0NBaercGiGeZxdw2qhViY/HE6A5b/6WN1bmMheBsV42IQovmlW0SFWssm3TK5N33DSdo1ovdk2fIikVxW8ya+bRQPcnczxA==" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-06:00" level=debug msg="completed challenge" Jan 10 12:37:20 volumio go-librespot[4820]: time="2026-01-10T12:37:20-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 " Jan 10 12:37:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:20 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:20 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:22 volumio ntpd[872]: IO: Deleting interface #11604 eth0, 169.254.29.247#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jan 10 12:37:23 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:23 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Jan 10 12:37:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:23 volumio go-librespot[4843]: go-librespot daemon starting... Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=debug msg="app state loaded" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-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]" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-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]" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-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]" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=info msg="zeroconf server listening on port 46777" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=debug msg="obtained new client token: AABlgPWx2lpD11r6V7iXbXaRJlcYofpITkFGIH/9jVMhQHtReqQqQKxWwJHJ2sD8sQkP4dVHK5nYBIUFV9lcTbmxH8TpJcbdJTCA1Q7lloLiZmooBaKFi/ggy2t/Vc2ftiWEi2zi/vCQqCXUR4aSdg39JvbwqYnHyk7hXSoQswEObNo7CQnTSktcaPcFwVQdtxf5TpDkgLnCuFWGgSX/ZW4qYk6azn1+JqdrycEcKkIlj696sAgJbmuZCg==" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-06:00" level=debug msg="completed challenge" Jan 10 12:37:24 volumio go-librespot[4844]: time="2026-01-10T12:37:24-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 " Jan 10 12:37:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:24 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.109.104 Jan 10 12:37:24 volumio avahi-daemon[664]: Registering new address record for 169.254.109.104 on eth0.IPv4. Jan 10 12:37:24 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:37:24 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:24 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:24 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.109.104 Jan 10 12:37:26 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.109.104 Jan 10 12:37:26 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.109.104 Jan 10 12:37:26 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:37:26 volumio avahi-daemon[664]: Withdrawing address record for 169.254.109.104 on eth0. Jan 10 12:37:26 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:37:26 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:26 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:26 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:26 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:26 volumio volumio[3319]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 10 12:37:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Jan 10 12:37:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 10 12:37:27 volumio go-librespot[4884]: go-librespot daemon starting... Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-06:00" level=info msg="running go-librespot 0.4.0" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-06:00" level=debug msg="app state loaded" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-06:00" level=debug msg="stored credentials not found" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-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]" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-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]" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-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]" Jan 10 12:37:27 volumio go-librespot[4885]: time="2026-01-10T12:37:27-06:00" level=info msg="zeroconf server listening on port 33493" Jan 10 12:37:28 volumio go-librespot[4885]: time="2026-01-10T12:37:28-06:00" level=debug msg="obtained new client token: AACOOI1iRlWpmTC4IbIbNXre4pPqg+l6kbdvN8DYIc2oHPGjXefX0cv+4pZo8O6Cn7shdVuSmj2gQ+pgrhddAp+X4V9JCe/A9Q4S2OgN2l4ZVITLlZVWh4Ypoq66J6Rloac8OepyV0mp4CfQYoUfyuTQN1/xeVOrsUNrLhV4ApEfUCs9PmNe5Wbvl8eO7W7hnYy2f4GH5n4w1G4zp8LFzjmRgxj7isIr3sNejzBk2AX/PmkSF3rTWtI=" Jan 10 12:37:28 volumio go-librespot[4885]: time="2026-01-10T12:37:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 10 12:37:29 volumio volumio[3319]: info: Initializing connection to go-librespot Websocket Jan 10 12:37:29 volumio go-librespot[4885]: time="2026-01-10T12:37:29-06:00" level=debug msg="new websocket client" Jan 10 12:37:29 volumio volumio[3319]: info: Connection to go-librespot Websocket established Jan 10 12:37:31 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.6.83 Jan 10 12:37:31 volumio avahi-daemon[664]: Registering new address record for 169.254.6.83 on eth0.IPv4. Jan 10 12:37:31 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 10 12:37:31 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:31 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.6.83 Jan 10 12:37:32 volumio ntpd[872]: IO: Listen normally on 11605 eth0 169.254.6.83:123 Jan 10 12:37:32 volumio ntpd[872]: IO: new interface(s) found: waking up resolver Jan 10 12:37:32 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.6.83 Jan 10 12:37:32 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.6.83 Jan 10 12:37:32 volumio avahi-daemon[664]: Withdrawing address record for 169.254.6.83 on eth0. Jan 10 12:37:32 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 10 12:37:32 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 10 12:37:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 10 12:37:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 10 12:37:32 volumio volumio[3319]: info: Getting Spotify volume Jan 10 12:37:32 volumio volumio[3319]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 10 12:37:33 volumio volumio[3319]: info: CoreCommandRouter::volumioGetState Jan 10 12:37:33 volumio volumio[3319]: info: CorePlayQueue::getTrack 0 Jan 10 12:37:33 volumio volumio[3319]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 12:37:33 volumio volumio[3319]: SPOTIFY: SPOTIFY VOLUME undefined Jan 10 12:37:33 volumio volumio[3319]: SPOTIFY: VOLUMIO VOLUME 100 Jan 10 12:37:33 volumio volumio[3319]: info: Aligning Spotify Volume to Volumio Volume Jan 10 12:37:33 volumio volumio[3319]: info: CoreCommandRouter::volumioGetState Jan 10 12:37:33 volumio volumio[3319]: info: CorePlayQueue::getTrack 0 Jan 10 12:37:33 volumio volumio[3319]: info: Setting Spotify Volume from Volumio: 100 Jan 10 12:37:33 volumio go-librespot[4885]: time="2026-01-10T12:37:33-06:00" level=debug msg="completed keyexchange" Jan 10 12:37:33 volumio go-librespot[4885]: time="2026-01-10T12:37:33-06:00" level=debug msg="completed challenge" Jan 10 12:37:33 volumio go-librespot[4885]: time="2026-01-10T12:37: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 " Jan 10 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 12:37:33 volumio volumio[3319]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 12:37:33 volumio volumio[3319]: Error: socket hang up Jan 10 12:37:33 volumio volumio[3319]: at connResetException (node:internal/errors:720:14) Jan 10 12:37:33 volumio volumio[3319]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 10 12:37:33 volumio volumio[3319]: at Socket.emit (node:events:526:35) Jan 10 12:37:33 volumio volumio[3319]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 10 12:37:33 volumio volumio[3319]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 10 12:37:33 volumio volumio[3319]: code: 'ECONNRESET', Jan 10 12:37:33 volumio volumio[3319]: response: undefined Jan 10 12:37:33 volumio volumio[3319]: } Jan 10 12:37:33 volumio volumio[3319]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 12:37:34 volumio ntpd[872]: IO: Deleting interface #11605 eth0, 169.254.6.83#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jan 10 12:37:34 volumio sudo[4953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 12:36' Jan 10 12:37:34 volumio sudo[4953]: 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"