Feb 25 19:30:07 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 25 19:30:07 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 25 19:30:07 volumio systemd[1]: setdatetime-helper.service: Consumed 1.450s CPU time. Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 25 19:30:07 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Feb 25 19:30:07 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 25 19:30:07 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Feb 25 19:30:07 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: A device disappeared from network Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: Device volumio disappeared from network Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: A device disappeared from network Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 25 19:30:07 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 25 19:30:07 volumio nmbd[1160]: [2026/02/25 19:30:07.390941, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 25 19:30:07 volumio nmbd[1160]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.1.136 for name WORKGROUP<1d>. Feb 25 19:30:07 volumio nmbd[1160]: This response was from IP 10.0.1.183, reporting an IP address of 10.0.1.183. Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: adding b73dfad4-f3c5-44d7-be44-54c696d8d289 Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: Found device Volumio Feb 25 19:30:07 volumio volumio[3213]: info: CoreCommandRouter::volumioGetState Feb 25 19:30:07 volumio volumio[3213]: info: CorePlayQueue::getTrack 0 Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: this is already registered, b73dfad4-f3c5-44d7-be44-54c696d8d289 Feb 25 19:30:07 volumio volumio[3213]: info: Discovery: Found device Volumio Feb 25 19:30:07 volumio volumio[3213]: info: CoreCommandRouter::volumioGetState Feb 25 19:30:07 volumio volumio[3213]: info: CorePlayQueue::getTrack 0 Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:07 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 25 19:30:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:08 volumio go-librespot[15090]: go-librespot daemon starting... Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=info msg="running go-librespot 0.4.0" Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="app state loaded" Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=info msg="zeroconf server listening on port 43521" Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="obtained new client token: AABrvimirI7+a4HUXZDYDsaX0kfiCKSiMSX16A8Vc/htr4WeiHMCwsQEOxjE38BjdV2nLGH8okWrWbUeuI14YwcuvdSrE6DyLrAIxpCVR/BM2ul+l+t+8F2qPevBzWNdqdvuHIgQ62gSv0+Ix8xUc4k6J/C0T8OABHadtYrHwfHS+pfC5M0MkycKya9xSmdRSG5e38eLhn3Nyz1SggPCD0VW8jB0lpWXQT1gpZduLkuxry49Zi1sQadFhg==" Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:08 volumio volumio[3213]: info: Initializing connection to go-librespot Websocket Feb 25 19:30:08 volumio go-librespot[15109]: time="2026-02-25T19:30:08-05:00" level=debug msg="new websocket client" Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:08 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio volumio[3213]: info: Connection to go-librespot Websocket established Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 19:30:09 volumio volumio[3213]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:09 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:09 volumio go-librespot[15109]: time="2026-02-25T19:30:09-05:00" level=debug msg="completed keyexchange" Feb 25 19:30:09 volumio go-librespot[15109]: time="2026-02-25T19:30:09-05:00" level=debug msg="completed challenge" Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:10 volumio go-librespot[15109]: time="2026-02-25T19:30:10-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 19:30:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 19:30:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:10 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:11 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:12 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 25 19:30:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:13 volumio go-librespot[16247]: go-librespot daemon starting... Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=info msg="running go-librespot 0.4.0" Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=debug msg="app state loaded" Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=info msg="zeroconf server listening on port 38133" Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=debug msg="obtained new client token: AADcFGwAXEqcusJElhFsjBJO13iVrk+XsaKK9QYYpYAWftCHUdttibsoFeq4OnVGIEwc3CBVIXzDzamQUBKZ2+vu6/3vNkanlSFDpUsZer6sYau0SgR9mZpX8Gm9GyZZyHj/DOuD2WfimoRzlhYTjR983y8TrQSTI/JFatDKt21MDsiJPgCQlyi8mi22Qv3bqmDcr33QanbeelyAtmI51/CwptWatLqmJgBaHb2dzwFsw8Vji0TEuAQ=" Feb 25 19:30:13 volumio go-librespot[16267]: time="2026-02-25T19:30:13-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:13 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio go-librespot[16267]: time="2026-02-25T19:30:14-05:00" level=debug msg="completed keyexchange" Feb 25 19:30:14 volumio go-librespot[16267]: time="2026-02-25T19:30:14-05:00" level=debug msg="completed challenge" Feb 25 19:30:14 volumio go-librespot[16267]: time="2026-02-25T19:30:14-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 19:30:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:14 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:15 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:16 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 25 19:30:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:17 volumio go-librespot[17109]: go-librespot daemon starting... Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=info msg="running go-librespot 0.4.0" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="app state loaded" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 19:30:17 volumio volumio[3213]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 19:30:17 volumio volumio[3213]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 19:30:17 volumio volumio[3213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 19:30:17 volumio volumio[3213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 19:30:17 volumio volumio[3213]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 19:30:17 volumio volumio[3213]: info: MyVolumio login type: Token Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio volumio[3213]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 19:30:17 volumio volumio[3213]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=info msg="zeroconf server listening on port 33479" Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="obtained new client token: AABXGt9/euoxMVassjUTr8Wzzl7B3agDeyXwQ/QNiCtu54Qb6YQP4DqA3eNcKEq/Us2J9Ep1yB42h1wV3iUbZOk+kzIHrXVUfOmAsBti3GdgWkHaJuxamJFiqGM7PVQdcYrle5Y9BREkb2OmkYEVSHvwIAS+GFitG33od/uTgVgCfxZnPfgUdSnMpAP5n3IJDsUaeJ8jV/fFVShIoqteW4YDykaA5TdFlgwQcpto4aSn52NczQ2WqcARHQ==" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="completed keyexchange" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=debug msg="completed challenge" Feb 25 19:30:17 volumio go-librespot[17119]: time="2026-02-25T19:30:17-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 19:30:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 19:30:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:17 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:18 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:19 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio volumio[3213]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 19:30:20 volumio volumio[3213]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 19:30:20 volumio volumio[3213]: info: Streaming services startup Feb 25 19:30:20 volumio volumio[3213]: info: Starting Streaming Daemon Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio volumio[3213]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 19:30:20 volumio sudo[17863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 19:30:20 volumio sudo[17863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio sudo[17863]: pam_unix(sudo:session): session closed for user root Feb 25 19:30:20 volumio volumio[3213]: info: Getting Spotify volume Feb 25 19:30:20 volumio sudo[17887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 25 19:30:20 volumio sudo[17887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 25 19:30:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:20 volumio volumio[3213]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 19:30:20 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:20 volumio go-librespot[17906]: go-librespot daemon starting... Feb 25 19:30:20 volumio volumio[3213]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 19:30:20 volumio volumio[3213]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 25 19:30:20 volumio volumio[3213]: errno: -111, Feb 25 19:30:20 volumio volumio[3213]: code: 'ECONNREFUSED', Feb 25 19:30:20 volumio volumio[3213]: syscall: 'connect', Feb 25 19:30:20 volumio volumio[3213]: address: '127.0.0.1', Feb 25 19:30:20 volumio volumio[3213]: port: 9879, Feb 25 19:30:20 volumio volumio[3213]: response: undefined Feb 25 19:30:20 volumio volumio[3213]: } Feb 25 19:30:20 volumio volumio[3213]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 19:30:20 volumio go-librespot[17920]: time="2026-02-25T19:30:20-05:00" level=info msg="running go-librespot 0.4.0" Feb 25 19:30:20 volumio go-librespot[17920]: time="2026-02-25T19:30:20-05:00" level=debug msg="app state loaded" Feb 25 19:30:20 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 25 19:30:20 volumio go-librespot[17920]: time="2026-02-25T19:30:20-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 19:30:20 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 25 19:30:20 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 25 19:30:20 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 25 19:30:20 volumio mpd_monitor.sh[17933]: MPD Monitor Service: Starting MPD Monitor Service Feb 25 19:30:20 volumio sudo[17887]: pam_unix(sudo:session): session closed for user root Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=info msg="zeroconf server listening on port 33515" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="obtained new client token: AABQL2sF9Z1Vu47/WyomOwedxfPM+r4vnX8JhLM5fMVC27/EgRCZRXjoJEEcxAS3Di8Up6gzzPuT/nNkDbpHyu6/p7FM4ow3TpMIvt6QlfumYOeLlLpPCZ/ilp1VkENtZzA4jEatXsi9tkufKgnrUa5VFHfJytqVCKKsT5RMP6FRBRcDDESljpycP6a2NlG+cHXwgHzbylZK6AHjr2ovgGXfGEINWUAa4LrxB38WJCfUCUWiq6RD+JaF1g==" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="completed keyexchange" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=debug msg="completed challenge" Feb 25 19:30:21 volumio go-librespot[17920]: time="2026-02-25T19:30:21-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 19:30:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 19:30:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:21 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:22 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:23 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 25 19:30:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 19:30:24 volumio go-librespot[18634]: go-librespot daemon starting... Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=info msg="running go-librespot 0.4.0" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="app state loaded" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=info msg="zeroconf server listening on port 44033" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="obtained new client token: AAAEyNeP/SBYC9HmqNW5OUgkKFPj98CbhXetukQgN4f5pp5ZP+JP0l6FbnNRTOKWnTwuf+K+abCBAaRgx30PUk+2IbEl+7r4p9mEqSDPxeKQ84qfhRCogXG6uFxJWN2s7KGAJemHegTCdYqOLK9XD/ww1CFWSI3VfldpJeKdF21GcgGoz4VF4qbIvDfHuJCzy4H6gfDyiSs7AIq64XveuArvvkaZ0tS+04yA64w9DAF0cfedjBqPKqO6ag==" Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="completed keyexchange" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=debug msg="completed challenge" Feb 25 19:30:24 volumio go-librespot[18646]: time="2026-02-25T19:30:24-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 19:30:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 19:30:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:24 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:25 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:26 volumio sudo[18973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 19:29' Feb 25 19:30:26 volumio sudo[18973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: removing interface Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: waiting for carrier Feb 25 19:30:26 volumio dhcpcd[803]: wlan1: removing interface 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"