Jan 11 16:28:00 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.116.220
Jan 11 16:28:00 volumio avahi-daemon[664]: Registering new address record for 169.254.116.220 on eth0.IPv4.
Jan 11 16:28:00 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:00 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:00 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:00 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:01 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.116.220
Jan 11 16:28:01 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:01 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Jan 11 16:28:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:01 volumio go-librespot[10130]: go-librespot daemon starting...
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=debug msg="app state loaded"
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:01 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.116.220
Jan 11 16:28:01 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.116.220
Jan 11 16:28:01 volumio avahi-daemon[664]: Withdrawing address record for 169.254.116.220 on eth0.
Jan 11 16:28:01 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:01 volumio sudo[10138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Jan 11 16:28:01 volumio sudo[10138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 16:28:01 volumio sudo[10140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Jan 11 16:28:01 volumio sudo[10140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 16:28:01 volumio sudo[10151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Jan 11 16:28:01 volumio sudo[10151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 16:28:01 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:01 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:01 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:01 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:01 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Jan 11 16:28:01 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Jan 11 16:28:01 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28: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 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28: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 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28: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 11 16:28:01 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=info msg="zeroconf server listening on port 42497"
Jan 11 16:28:01 volumio sudo[10138]: pam_unix(sudo:session): session closed for user root
Jan 11 16:28:01 volumio sudo[10140]: pam_unix(sudo:session): session closed for user root
Jan 11 16:28:01 volumio sudo[10151]: pam_unix(sudo:session): session closed for user root
Jan 11 16:28:01 volumio mpd_monitor.sh[10163]: MPD Monitor Service: Starting MPD Monitor Service
Jan 11 16:28:01 volumio volumio[9436]: info: Successfully started MPD Monitor
Jan 11 16:28:01 volumio volumio[9436]: info: Successfully started MPD Monitor
Jan 11 16:28:01 volumio volumio[9436]: info: Successfully started MPD Monitor
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=debug msg="obtained new client token: AABqBGgcK3bkn8ez0w4px0L0Evalt756j4l2tXDOK+z3Y0f6P3Q+m1qBUcYkq9n7Lho5kb801z9RQGGDjXXVflamnKqGEw1k0He482v2hQTELLV+5yGmdJumjOiq8bbYEyTM58etfTYvj709JQxmpzA0DU4L/yacVE6AtwW6BUde0wrah8kINnzHAzxF5M86mGv1Pwjjd1RoZUZHzfb67cnSrvyYPHeY+trE78nqpaozydyy4CkL/7+a/g=="
Jan 11 16:28:01 volumio go-librespot[10131]: time="2026-01-11T16:28:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:02 volumio go-librespot[10131]: time="2026-01-11T16:28:02-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:02 volumio go-librespot[10131]: time="2026-01-11T16:28:02-06:00" level=debug msg="completed challenge"
Jan 11 16:28:02 volumio go-librespot[10131]: time="2026-01-11T16:28: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 11 16:28:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:04 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:04 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Jan 11 16:28:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:05 volumio go-librespot[10167]: go-librespot daemon starting...
Jan 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-06:00" level=debug msg="app state loaded"
Jan 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-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 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-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 11 16:28:05 volumio go-librespot[10168]: time="2026-01-11T16:28:05-06:00" level=info msg="zeroconf server listening on port 44527"
Jan 11 16:28:06 volumio go-librespot[10168]: time="2026-01-11T16:28:06-06:00" level=debug msg="obtained new client token: AAAcCbN6UH7lSUUtZLtd70MqQc7yBB0Qb9dJEpHIKM3b2GyXQPKfjcPYUzLVvulMLMZVnDBth//SU3UiXrujJBA23MFZOzeSFO2NA4Qb4jTWklj0ks8PDpBGqbj+aYHJ5QqwjDGn1uSLsWjyN9bFYbGoPBhVGlOCvf9lxB1vX9w69s278V7axWjYYiJ5i+yKtgEb3Wm2qT1HUIt4VGy2rLrXNqveoliaU3mcN7hzS80h/40VzU0IPsjJCw=="
Jan 11 16:28:06 volumio go-librespot[10168]: time="2026-01-11T16:28:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:06 volumio go-librespot[10168]: time="2026-01-11T16:28:06-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:06 volumio go-librespot[10168]: time="2026-01-11T16:28:06-06:00" level=debug msg="completed challenge"
Jan 11 16:28:06 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jan 11 16:28:06 volumio go-librespot[10168]: time="2026-01-11T16:28:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:28:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:06 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.182.177
Jan 11 16:28:06 volumio avahi-daemon[664]: Registering new address record for 169.254.182.177 on eth0.IPv4.
Jan 11 16:28:06 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:06 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:06 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:06 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:07 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.182.177
Jan 11 16:28:07 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.182.177
Jan 11 16:28:07 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.182.177
Jan 11 16:28:07 volumio avahi-daemon[664]: Withdrawing address record for 169.254.182.177 on eth0.
Jan 11 16:28:07 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:08 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:08 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:08 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin bluetooth to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin multiroom to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin metavolumio to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin cd_controller to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin qobuzconnect to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin smart_inputs to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: Adding plugin tidalconnect to MyMusic Plugins
Jan 11 16:28:08 volumio volumio[9436]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jan 11 16:28:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Jan 11 16:28:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:09 volumio go-librespot[10224]: go-librespot daemon starting...
Jan 11 16:28:09 volumio go-librespot[10225]: time="2026-01-11T16:28:09-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:09 volumio go-librespot[10225]: time="2026-01-11T16:28:09-06:00" level=debug msg="app state loaded"
Jan 11 16:28:09 volumio go-librespot[10225]: time="2026-01-11T16:28:09-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:09 volumio go-librespot[10225]: time="2026-01-11T16:28:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-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 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-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 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=info msg="zeroconf server listening on port 45139"
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=debug msg="obtained new client token: AAACV2LO2MZOMpdzMIbTvooQ9cMksq/LCtHr/78aWQajam2evxg7GlMtTAvw0jAu2jL8e59GIK8HB7152QDKk/1VsCdLx8c8nK662g6FgnQOAHpUfXr2NuLwzq75E34AUYIu6SBCsS5aYSCadDYXAXpCQDc2OXrxx4FxnGEWV8w8SGQ3LqYjTr9xK1NIWDg2gHGcjfNmLy77YE7PJ9mWzwEah8ELFWJ/DhK4zPKVJzX8WdZ/9XzXItw="
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=debug msg="completed challenge"
Jan 11 16:28:10 volumio volumio[9436]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jan 11 16:28:10 volumio volumio[9436]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jan 11 16:28:10 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:10 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:10 volumio volumio[9436]: info: Starting MyVolumio Remote Streaming Endpoints
Jan 11 16:28:10 volumio go-librespot[10225]: time="2026-01-11T16:28:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:28:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:10 volumio volumio[9436]: info: MyVolumio login type: Token
Jan 11 16:28:10 volumio volumio[9436]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jan 11 16:28:10 volumio volumio[9436]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jan 11 16:28:11 volumio volumio[9436]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jan 11 16:28:11 volumio volumio[9436]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jan 11 16:28:11 volumio volumio[9436]: info: Streaming services startup
Jan 11 16:28:11 volumio volumio[9436]: info: Starting Streaming Daemon
Jan 11 16:28:11 volumio sudo[10235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 11 16:28:11 volumio sudo[10235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 16:28:11 volumio volumio[9436]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jan 11 16:28:11 volumio sudo[10235]: pam_unix(sudo:session): session closed for user root
Jan 11 16:28:11 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:11 volumio volumio[9436]: error: Cannot start Volumio Streaming Daemon
Jan 11 16:28:11 volumio volumio[9436]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 11 16:28:11 volumio volumio[9436]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 11 16:28:11 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:12 volumio volumio[9436]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jan 11 16:28:12 volumio volumio[9436]: info: MyVolumio token set successfully
Jan 11 16:28:12 volumio volumio[9436]: info: MYVOLUMIO: Adding device
Jan 11 16:28:12 volumio volumio[9436]: info: MYVOLUMIO: Evaluating Server
Jan 11 16:28:12 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.7.9
Jan 11 16:28:12 volumio avahi-daemon[664]: Registering new address record for 169.254.7.9 on eth0.IPv4.
Jan 11 16:28:12 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:12 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:12 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:12 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:12 volumio volumio[9436]: info: MyVolumio status changed
Jan 11 16:28:12 volumio volumio[9436]: info: Streaming services startup
Jan 11 16:28:12 volumio volumio[9436]: info: Starting Streaming Daemon
Jan 11 16:28:12 volumio volumio[9436]: info: Removing browser output: myVolumio user plan is not superstar
Jan 11 16:28:12 volumio volumio[9436]: info: Removing audio output:
Jan 11 16:28:12 volumio volumio[9436]: info: Stoppping Tunnel 1
Jan 11 16:28:12 volumio sudo[10279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 11 16:28:12 volumio sudo[10279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 16:28:12 volumio sudo[10281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jan 11 16:28:12 volumio sudo[10281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 16:28:12 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 11 16:28:12 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 11 16:28:12 volumio sudo[10279]: pam_unix(sudo:session): session closed for user root
Jan 11 16:28:12 volumio volumio[9436]: error: Cannot start Volumio Streaming Daemon
Jan 11 16:28:12 volumio volumio[9436]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 11 16:28:12 volumio volumio[9436]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 11 16:28:12 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 11 16:28:12 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 11 16:28:12 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 11 16:28:12 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 11 16:28:12 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 11 16:28:12 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 11 16:28:12 volumio sudo[10281]: pam_unix(sudo:session): session closed for user root
Jan 11 16:28:12 volumio volumio[9436]: info: Remote SSH Stopped
Jan 11 16:28:12 volumio volumio[9436]: info: Setting Geolocation for MyVolumio to us1
Jan 11 16:28:12 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:12 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:12 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:13 volumio volumio[9436]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 11 16:28:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.7.9
Jan 11 16:28:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Jan 11 16:28:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:13 volumio go-librespot[10284]: go-librespot daemon starting...
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=debug msg="app state loaded"
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-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 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-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 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-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 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=info msg="zeroconf server listening on port 38153"
Jan 11 16:28:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.7.9
Jan 11 16:28:13 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.7.9
Jan 11 16:28:13 volumio avahi-daemon[664]: Withdrawing address record for 169.254.7.9 on eth0.
Jan 11 16:28:13 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=debug msg="obtained new client token: AADtQSjsSHBB1eKMOWEoB1Oxq7jsw6twOvvPNM7hQtCTIKnJWLLQJpNoMQTxgN78FCxhsdBQ0tI82w5fxx9XOM/GJmS03C6uBGiTkAFPuVlOcUGrn+r+JTH7Y8UQN6MnaDjBETH5HDs4mfLAyooz25h87cR/eSRnXJEW2WGNCEFN/4Wyh7uUjJb/v66y7v8SrYukEU0kRisraAmA3GMQcK/Xqq8sWkJ4YWAqCXuFcQ76RAJKiBo9tKOGgQ=="
Jan 11 16:28:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:13 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28:13-06:00" level=debug msg="completed challenge"
Jan 11 16:28:13 volumio volumio[9436]: info: Updating MyVolumio device info
Jan 11 16:28:13 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:13 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:13 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:13 volumio go-librespot[10285]: time="2026-01-11T16:28: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 11 16:28:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:14 volumio volumio[9436]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 11 16:28:14 volumio kernel: hwmon hwmon1: Voltage normalised
Jan 11 16:28:14 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:14 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:17 volumio volumio[9436]: info: MYVOLUMIO: Adding device
Jan 11 16:28:17 volumio volumio[9436]: info: MYVOLUMIO: Evaluating Server
Jan 11 16:28:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Jan 11 16:28:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:17 volumio go-librespot[10313]: go-librespot daemon starting...
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="app state loaded"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-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 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-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 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=info msg="zeroconf server listening on port 34599"
Jan 11 16:28:17 volumio volumio[9436]: info: Setting Geolocation for MyVolumio to us1
Jan 11 16:28:17 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:17 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:17 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="obtained new client token: AADL+YkYoFcbAF9kVHUQOlv3g/MqQ6Gw1bEGfElXX5TXpYvu7opMHK6MlbFjw2bVGZqn3HzUpW7uFX+6jgcn1Xm7jp9H1wwwGxX89+nOXDLycc0aOmGLBMvTOT7vjYTZpB+JQ9/IFrrxguQ0lC23/3xvbpzaddh0hGQU0e+3iE3NJ+WpF/d1lYMsPL06JW8ysR9ZS/jJ7HNbUZHzJ2C9C6qE3+lYafYjIVoWWFJabNj/RleA38qVzFjFfA=="
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-06:00" level=debug msg="completed challenge"
Jan 11 16:28:17 volumio volumio[9436]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 11 16:28:17 volumio go-librespot[10320]: time="2026-01-11T16:28:17-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 11 16:28:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:17 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:17 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 11 16:28:18 volumio volumio-remote-updater[14193]: Test mode disabled
Jan 11 16:28:18 volumio volumio-remote-updater[14193]: Alpha mode disabled
Jan 11 16:28:18 volumio volumio-remote-updater[14193]: Alpha legacy test mode disabled
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 11 16:28:18 volumio volumio[9436]: info: Updating MyVolumio device info
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:18 volumio volumio[9436]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Nodejs 20.5.1 version pinning with static package fallback
\n
\n","title":"Update v4.073","updateavailable":true}
Jan 11 16:28:18 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 11 16:28:19 volumio volumio[9436]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 11 16:28:19 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.85.42
Jan 11 16:28:19 volumio avahi-daemon[664]: Registering new address record for 169.254.85.42 on eth0.IPv4.
Jan 11 16:28:19 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.85.42
Jan 11 16:28:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.85.42
Jan 11 16:28:20 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.85.42
Jan 11 16:28:20 volumio avahi-daemon[664]: Withdrawing address record for 169.254.85.42 on eth0.
Jan 11 16:28:20 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:20 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:20 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:20 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Jan 11 16:28:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:20 volumio go-librespot[10386]: go-librespot daemon starting...
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="app state loaded"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-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 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-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 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=info msg="zeroconf server listening on port 36259"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="obtained new client token: AACStUP9oohG4zqK1DKoGWgEG7irB2cUCjHlVGSe+RiOOyx9ndx6/pyznap4hqCkNhMOXgRagRnsCkVkAd9ayFz0590pHaelcJJuAK/cVB9sGJOnmrfX2f4UnPwqWGKq/4/y9RY/gN7mKbQYOS6Mlbd23qgUA/riiJ5l3ourscBmvCCptOG72FkGzd1Mco0bqmrBh3Vbb9ECerWq9Dm//lMy1AY38N32nyBYeuJGCNTyQxf+M3Z1nligug=="
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=debug msg="completed challenge"
Jan 11 16:28:21 volumio go-librespot[10387]: time="2026-01-11T16:28:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:28:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:23 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:23 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Jan 11 16:28:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:24 volumio go-librespot[10397]: go-librespot daemon starting...
Jan 11 16:28:24 volumio go-librespot[10398]: time="2026-01-11T16:28:24-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:24 volumio go-librespot[10398]: time="2026-01-11T16:28:24-06:00" level=debug msg="app state loaded"
Jan 11 16:28:24 volumio go-librespot[10398]: time="2026-01-11T16:28:24-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:24 volumio go-librespot[10398]: time="2026-01-11T16:28:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-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 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-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 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=info msg="zeroconf server listening on port 40627"
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=debug msg="obtained new client token: AADQoYHFR0IRcHZvWj+KzWITXTKeacjq5uPwBO2t2zQZxYRi/4OaXGrqZLywjYaRfinNaSaFX5PCG9uqDyn1e5N9Hrh3Mx4zoiLUbhU8o6OJWfrXOY7REkqBLsFEXOgIhsc71GKe7dMCDG8hih5PnnLRekHoj13Hm4HfaeXQwR1hNA1Ensra4Vgny1HHSGcQEUIrADKztxruejRa71PYCHSbgsjeQnrYKhFhjHsCF6RBAzzI19+Rylc="
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-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 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=debug msg="completed challenge"
Jan 11 16:28:25 volumio go-librespot[10398]: time="2026-01-11T16:28:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:28:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:25 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 11 16:28:25 volumio volumio[9436]: 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 11 16:28:25 volumio volumio[9436]: info: CoreCommandRouter::volumioGetState
Jan 11 16:28:25 volumio volumio[9436]: info: CorePlayQueue::getTrack 0
Jan 11 16:28:25 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.166.215
Jan 11 16:28:25 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:25 volumio avahi-daemon[664]: Registering new address record for 169.254.166.215 on eth0.IPv4.
Jan 11 16:28:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:26 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.166.215
Jan 11 16:28:26 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:26 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:27 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.166.215
Jan 11 16:28:27 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.166.215
Jan 11 16:28:27 volumio avahi-daemon[664]: Withdrawing address record for 169.254.166.215 on eth0.
Jan 11 16:28:27 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:27 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:27 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:27 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:27 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Jan 11 16:28:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:28 volumio go-librespot[10453]: go-librespot daemon starting...
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=debug msg="app state loaded"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-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 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-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 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-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 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=info msg="zeroconf server listening on port 46005"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=debug msg="obtained new client token: AAAU+Xob2WxzBo3Er4j+LaeNnviC/3w7oZcQC7PXe4KAFrn31ywpubnshI8Cs6ZZAhEfVe1yxNtUOvSN1/m3NzXPU2MvPk18sJsN1mzAHaXAcGSNMJDD8xYlSiSzlCpKNs9WKoXYLhiEWKwLXlySDRPzARgop8yXXi6l+qlvjSJdOY4DDkomTz9n4D1+fhL04OoQ7UETampZEzBxW+YFmos4C7dPeeg3/YSsyNrzdcqOHYrdJN1hu3bG5Q=="
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:28 volumio go-librespot[10454]: time="2026-01-11T16:28:28-06:00" level=debug msg="completed challenge"
Jan 11 16:28:29 volumio go-librespot[10454]: time="2026-01-11T16:28:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:28:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:29 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jan 11 16:28:29 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:29 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 11 16:28:30 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 16:28:31 volumio volumio[9436]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 11 16:28:31 volumio volumio[9436]: info: Completed starting MyVolumio Plugin
Jan 11 16:28:31 volumio volumio[9436]: [Metrics] CommandRouter: 66s 85.05ms
Jan 11 16:28:31 volumio volumio[9436]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 11 16:28:31 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 16:28:31 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 16:28:31 volumio volumio[9436]: info: CoreCommandRouter::Close All Modals sent
Jan 11 16:28:31 volumio volumio[9436]: info: CoreCommandRouter::Close All Modals sent
Jan 11 16:28:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Jan 11 16:28:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:32 volumio go-librespot[10465]: go-librespot daemon starting...
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.95.228
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:32 volumio avahi-daemon[664]: Registering new address record for 169.254.95.228 on eth0.IPv4.
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=debug msg="app state loaded"
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-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 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-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 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-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 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=info msg="zeroconf server listening on port 46651"
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=debug msg="obtained new client token: AAAtpZeVrv/7EkqdGnQ1RB2DNrGmQgA5XitWO5W9C8LPc2iwzg7pdoXZNHD5uyAaERiuyoLk1o8CmRwhcnCGq8dXJeVXSvSoIrxLl6njKobQ8zNr3E5utxDxz/j5x1RDvLj8DpOnHjv841GodpGdRNWytMfpBBoAF8aE1BV8YNhbpvQuu7Ld/cnRUU+p3XvoqmlLPBOFbVy6vGubTA0+OggVvqxxUte/++7Y96koS65Uni4whxxtc9aisA=="
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.95.228
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:32 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 11 16:28:32 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28:32-06:00" level=debug msg="completed challenge"
Jan 11 16:28:32 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 11 16:28:32 volumio go-librespot[10466]: time="2026-01-11T16:28: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 11 16:28:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:32 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:32 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.95.228
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.95.228
Jan 11 16:28:32 volumio avahi-daemon[664]: Withdrawing address record for 169.254.95.228 on eth0.
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:32 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:35 volumio kernel: hwmon hwmon1: Voltage normalised
Jan 11 16:28:35 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:35 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Jan 11 16:28:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:36 volumio go-librespot[10509]: go-librespot daemon starting...
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="app state loaded"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=info msg="zeroconf server listening on port 43541"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="obtained new client token: AAAnwLHm65hbGpTzUPuhBHQ0/QmjFDCM6zHlhpvokTSWyMy5guo2MNU2yhLi0Jh5qzFNWNy4vPYUUBy/bD6ua98xrBtYe6zEW3IWWQ9WH1/pZ8SIKKucmtV80awKHSmJlqgLWHnB13Beem7PmUDcQk7GxtfDhwhTvFqPtoZe8qTPBk9UOmauEgIFFySIjK4F7TAoXxwIdVgyJ9YNQkrz4Jmob2+W05fI1pZoWaTkhmxZDzejZMAwtcZ6mg=="
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-06:00" level=debug msg="completed challenge"
Jan 11 16:28:36 volumio go-librespot[10510]: time="2026-01-11T16:28:36-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 11 16:28:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:38 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.54.143
Jan 11 16:28:38 volumio avahi-daemon[664]: Registering new address record for 169.254.54.143 on eth0.IPv4.
Jan 11 16:28:38 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:38 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:38 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:38 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:38 volumio volumio[9436]: info: BOOT COMPLETED
Jan 11 16:28:38 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:38 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:39 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.54.143
Jan 11 16:28:39 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.54.143
Jan 11 16:28:39 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.54.143
Jan 11 16:28:39 volumio avahi-daemon[664]: Withdrawing address record for 169.254.54.143 on eth0.
Jan 11 16:28:39 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Jan 11 16:28:39 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:39 volumio go-librespot[10565]: go-librespot daemon starting...
Jan 11 16:28:39 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:39 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:39 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:39 volumio go-librespot[10566]: time="2026-01-11T16:28:39-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:39 volumio go-librespot[10566]: time="2026-01-11T16:28:39-06:00" level=debug msg="app state loaded"
Jan 11 16:28:39 volumio go-librespot[10566]: time="2026-01-11T16:28:39-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:39 volumio go-librespot[10566]: time="2026-01-11T16:28:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-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 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-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 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=info msg="zeroconf server listening on port 35447"
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=debug msg="obtained new client token: AACrtsoKw1RAjMgtvIhkdaUtH7Sf2yFKNh6H38P5r5ZQ9bPkM5x9Ru6V+d3UtXXVig+BuQpuFoReLEgaTs1wGwTbgaB0xlvb9B4CugfxJOPbeBR11yXGXCKpqx6q1H78cMLF+gfYqvzFqoVE2lXiskMdWioLNu61ySEb47IaouL1g4WK/JoLKPpe8KPQWztYO/L6xVZI9elivlsnHe3wGvkqjBb22NK1k4/aK0P5rd2u0+vbR2E5fXU="
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=debug msg="completed challenge"
Jan 11 16:28:40 volumio go-librespot[10566]: time="2026-01-11T16:28:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:28:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:41 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:41 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Jan 11 16:28:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:43 volumio go-librespot[10577]: go-librespot daemon starting...
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=debug msg="app state loaded"
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-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 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-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 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-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 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=info msg="zeroconf server listening on port 36441"
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=debug msg="obtained new client token: AADYPXgVxZ1cGklmV4+JvTJ8tHFvDBapVi/Gyhtu45esUgUsJbo6i2hBJdjjhvscNfbeIs6JQzL5I0v4SdcWlHvsuvLaim5uFtMHuNoto2zVPS3tC77iJ0pzDXnhlTmku+NiObRd8ysfmFHYw5livrlTq2Gupy40s98NCkAOVLxKiVVnTIh8DloJSo66AVFTrekl4HwaBsx+trfCXc7y61jUeOE4kTvxYr7dYiPeJ3PPbRCULhTNjnTC1g=="
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-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 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused"
Jan 11 16:28:43 volumio go-librespot[10578]: time="2026-01-11T16:28:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:80"
Jan 11 16:28:44 volumio go-librespot[10578]: time="2026-01-11T16:28:44-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:44 volumio go-librespot[10578]: time="2026-01-11T16:28:44-06:00" level=debug msg="completed challenge"
Jan 11 16:28:44 volumio go-librespot[10578]: time="2026-01-11T16:28:44-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 11 16:28:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:44 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.135.72
Jan 11 16:28:44 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:44 volumio avahi-daemon[664]: Registering new address record for 169.254.135.72 on eth0.IPv4.
Jan 11 16:28:44 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:44 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:44 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:44 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.135.72
Jan 11 16:28:44 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:44 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:45 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.135.72
Jan 11 16:28:45 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.135.72
Jan 11 16:28:45 volumio avahi-daemon[664]: Withdrawing address record for 169.254.135.72 on eth0.
Jan 11 16:28:45 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:45 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:45 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:45 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:45 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Jan 11 16:28:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:47 volumio go-librespot[10617]: go-librespot daemon starting...
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=debug msg="app state loaded"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-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 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-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 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-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 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=info msg="zeroconf server listening on port 46725"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=debug msg="obtained new client token: AACgVytB3v/wql14u1ikxYcVu5oMyEqKdzUnCD8pM18U60g6ranhEvjGQlr4c7ZZxbVH3KWqxiwJrBYgdm467q3r/MxrMVF3CZdCio53Qv9/oUEHhUIW0ucYUDNaG+UzJzjuUEcb70AVSXmveT5mKEgTfFfv1Nw+TWfVMaWRKrFNi5dlPb3S61haammYhb5d4ey5/T/b9tuyX6ezEIUNvyVJ2A+j87Zu5vjgdac/xVFkTaGUJ9LWhSMFDA=="
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28:47-06:00" level=debug msg="completed challenge"
Jan 11 16:28:47 volumio go-librespot[10618]: time="2026-01-11T16:28: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 11 16:28:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:47 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:47 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:48 volumio volumio[9436]: info: UPDATER: Scheduling automatic update
Jan 11 16:28:48 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Jan 11 16:28:48 volumio volumio[9436]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Jan 11 16:28:48 volumio volumio[9436]: info: UPDATER: Auto update will take place at: Mon Jan 12 2026 04:47:19 GMT-0600 (Central Standard Time)
Jan 11 16:28:49 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.70.167
Jan 11 16:28:49 volumio avahi-daemon[664]: Registering new address record for 169.254.70.167 on eth0.IPv4.
Jan 11 16:28:49 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:50 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:50 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:50 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:50 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.70.167
Jan 11 16:28:50 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.70.167
Jan 11 16:28:50 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.70.167
Jan 11 16:28:50 volumio avahi-daemon[664]: Withdrawing address record for 169.254.70.167 on eth0.
Jan 11 16:28:50 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:50 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:50 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:50 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:50 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:50 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:50 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Jan 11 16:28:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:50 volumio go-librespot[10671]: go-librespot daemon starting...
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=debug msg="app state loaded"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-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 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-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 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-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 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=info msg="zeroconf server listening on port 42957"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=debug msg="obtained new client token: AADPaGl4fkt7KwQ7E5RKqrjIHzD/fzqqzL96ZESnYY6dZE7M0C+Ou9NmPjvKtPrF0JPYJ2WiH3cYAGr5S7sP3QAqN1ME2V0gP9Lijdxf2TfImKCFwc5SzQokackGuajTHibPtALF7euFnlbzDVdWn2PsrUAzEP0Uj5zdgf5M2jPuTqpOYBhYtP/G0cO5Qp+pju3VlZKg4ci2uPz+ymhQEA87B0ckQOP7Eja8BLRa/hMu/xTBOm9Au8FedQ=="
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-06:00" level=debug msg="completed challenge"
Jan 11 16:28:51 volumio go-librespot[10672]: time="2026-01-11T16:28:51-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 11 16:28:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:53 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:53 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Jan 11 16:28:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:54 volumio go-librespot[10679]: go-librespot daemon starting...
Jan 11 16:28:54 volumio go-librespot[10680]: time="2026-01-11T16:28:54-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:54 volumio go-librespot[10680]: time="2026-01-11T16:28:54-06:00" level=debug msg="app state loaded"
Jan 11 16:28:54 volumio go-librespot[10680]: time="2026-01-11T16:28:54-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:54 volumio go-librespot[10680]: time="2026-01-11T16:28:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-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 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-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 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-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 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-06:00" level=info msg="zeroconf server listening on port 44739"
Jan 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-06:00" level=debug msg="obtained new client token: AABVwuuXYqVdldSFEeA53kghDRqFBhHO3XsFgZT8tPC4DN4+xLETcBOFBBYU6yLLYT4/GpOw+jotCXhruEWFVA8+6gJ064Od6keBpqdhqrc7caP2MVlhvvYXj91vy0QOYB8TZHz5USujpp+GRnFM5aIlY+HAEIfbMzqqdWNjk+0l5pIwgF1EWPma/+MJ3wHpXyGaQ90za6+TK4zt6GScTz+q47vPvQMMeffxa3m1ELo9Gj+P2lGaHOI="
Jan 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-06:00" level=debug msg="completed challenge"
Jan 11 16:28:55 volumio go-librespot[10680]: time="2026-01-11T16:28:55-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 11 16:28:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:55 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.26.104
Jan 11 16:28:55 volumio avahi-daemon[664]: Registering new address record for 169.254.26.104 on eth0.IPv4.
Jan 11 16:28:55 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:28:55 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:55 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:55 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:55 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.26.104
Jan 11 16:28:56 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.26.104
Jan 11 16:28:56 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.26.104
Jan 11 16:28:56 volumio avahi-daemon[664]: Withdrawing address record for 169.254.26.104 on eth0.
Jan 11 16:28:56 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:28:56 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:28:56 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:56 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:28:56 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:28:56 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:56 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:28:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Jan 11 16:28:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:28:58 volumio go-librespot[10735]: go-librespot daemon starting...
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=debug msg="app state loaded"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=debug msg="stored credentials not found"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-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 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-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 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-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 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=info msg="zeroconf server listening on port 33877"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=debug msg="obtained new client token: AABulvWSJ78xKwsqXvFQ5TsTHokI+XbvcpozZeamh05ypmnsgtdwSGxz5OOX1qdda3zQQVboPzvIKCG0RPZf+P61DkFBkTj1odrE/ndWNzBCodCvnDyldl2SyXkF+Yq6+yDErgc1LtVENkL+W8mM5Mh5wmm4x9iQd4gqo5gK4/dDfgwA83DByu4exeFzjSFLCeY/RU3UbS/1UMxm47r+QCBdjl1NVL951Z+IXZC8YU0oZdqp50eGUrEOvQ=="
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=debug msg="completed keyexchange"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28:58-06:00" level=debug msg="completed challenge"
Jan 11 16:28:58 volumio go-librespot[10736]: time="2026-01-11T16:28: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 11 16:28:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:28:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:28:59 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:28:59 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:01 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.195.32
Jan 11 16:29:01 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:01 volumio avahi-daemon[664]: Registering new address record for 169.254.195.32 on eth0.IPv4.
Jan 11 16:29:01 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:01 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:01 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Jan 11 16:29:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:02 volumio go-librespot[10762]: go-librespot daemon starting...
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="app state loaded"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.195.32
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-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 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-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 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=info msg="zeroconf server listening on port 39961"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="obtained new client token: AACmMQDK4xbgisS2R3lhdA/MLBm4iCpneEd1q52Ztw8cL/RDA6NsujZPAtX0MMt3LglKtQg0WYKuvDgdEl0OwLaKEBX6gXe+S8BgdROqX61DQ6MDu375DAjSuxVcz5+29fKwuKGDHQIHb/AyiK/FKSJQK0gMgHBKigAQA2cB83WLUzWb+27Im57pl+EZW7CLZcGNEob2RBTUxI70Mm8TLj8tw9gCUxzXXMYMxewOkTVbFblyEc489YsJ9g=="
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29:02-06:00" level=debug msg="completed challenge"
Jan 11 16:29:02 volumio go-librespot[10763]: time="2026-01-11T16:29: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 11 16:29:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.195.32
Jan 11 16:29:02 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.195.32
Jan 11 16:29:02 volumio avahi-daemon[664]: Withdrawing address record for 169.254.195.32 on eth0.
Jan 11 16:29:02 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:02 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:02 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:02 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Jan 11 16:29:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:05 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:05 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:05 volumio go-librespot[10786]: go-librespot daemon starting...
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="app state loaded"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=info msg="zeroconf server listening on port 35549"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="obtained new client token: AAB+zqk5xkV99ahvvQAlTPDgugWaqzx8bgMQuKV+0RdX3HXkNygu3UN8PfEcUFgX3SJikySRhDFJTWPUB+JpisOT0u+Frkk1NMQEz7dWK80bZAMz+sm7qSN6qXhgxTwnocNic18KgAsrLqO7ZBaZGfPcGIVWnFCOXeL1XbpnNmg5hBVSeSFQuXcKzipdkzebxkhg78Z+k6VIwxRUWUdfxJ2rlhpdlPVUrDLQm/vJcYCLe2xDHmpxbU7zSA=="
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=debug msg="completed challenge"
Jan 11 16:29:06 volumio go-librespot[10787]: time="2026-01-11T16:29:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:29:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.115.208
Jan 11 16:29:08 volumio avahi-daemon[664]: Registering new address record for 169.254.115.208 on eth0.IPv4.
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.115.208
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.115.208
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.115.208
Jan 11 16:29:08 volumio avahi-daemon[664]: Withdrawing address record for 169.254.115.208 on eth0.
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:08 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:08 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:08 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Jan 11 16:29:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:09 volumio go-librespot[10840]: go-librespot daemon starting...
Jan 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29:09-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29:09-06:00" level=debug msg="app state loaded"
Jan 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29:09-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29: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 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29: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 11 16:29:09 volumio go-librespot[10841]: time="2026-01-11T16:29: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 11 16:29:10 volumio go-librespot[10841]: time="2026-01-11T16:29:10-06:00" level=info msg="zeroconf server listening on port 40947"
Jan 11 16:29:10 volumio go-librespot[10841]: time="2026-01-11T16:29:10-06:00" level=debug msg="obtained new client token: AADc1UBl51hq1aNTeHLzKvDu+uFGs+TapwnN7MqN6Udzcf3NCE759JE54avW08fOtCr114oRRX1JIiE3m9WvqJNnpK0XafgcdMoVH9UhC3qBcreRkhScVt9IcFjSy9ntH69qCA+jIkjcZDT89g6rhVwI3zbCUPB5XFEIVrpzBoGpgLnlP5K1Mw4PPsrDSnCuE471ZcqwyHowzvsQR8Ws7FYENwStyZ9zShQEsrwgCyOSYeZkNmLoJ0k="
Jan 11 16:29:10 volumio go-librespot[10841]: time="2026-01-11T16:29:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:10 volumio go-librespot[10841]: time="2026-01-11T16:29:10-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:10 volumio go-librespot[10841]: time="2026-01-11T16:29:10-06:00" level=debug msg="completed challenge"
Jan 11 16:29:10 volumio go-librespot[10841]: time="2026-01-11T16:29:10-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:29:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:11 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:11 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Jan 11 16:29:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:13 volumio go-librespot[10848]: go-librespot daemon starting...
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=debug msg="app state loaded"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-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 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-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 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-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 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=info msg="zeroconf server listening on port 35405"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=debug msg="obtained new client token: AABltg8EHQ40kt92Hg7vrpW+F01RESuxShoCsZzuMcXDAVLSyrGcJrBIl5sUJyOCcB6Kzu8/AMqvr4mCXV6zkeMPLFxXpSI6fXnnZTAS5zAZ0MwBnSjyXefSidZD1vQGO1ErcokQivIbKTT9ptzmEhGYH94iLSWk0mBSv7Bpf2nt9EmFd+ad3/EZffzJtnD4jfpw5og3AngIrkFkeKw6lCdjS/Jq8ex/VJy9Lz+Y1C1RDj8BIE64ydYVQw=="
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29:13-06:00" level=debug msg="completed challenge"
Jan 11 16:29:13 volumio go-librespot[10849]: time="2026-01-11T16:29: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 11 16:29:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.18.95
Jan 11 16:29:14 volumio avahi-daemon[664]: Registering new address record for 169.254.18.95 on eth0.IPv4.
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.18.95
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.18.95
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.18.95
Jan 11 16:29:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.18.95 on eth0.
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:14 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:14 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Jan 11 16:29:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:17 volumio go-librespot[10888]: go-librespot daemon starting...
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="app state loaded"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-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 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-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 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=info msg="zeroconf server listening on port 45643"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="obtained new client token: AACnAG2KUd8wkVQYxsqVAuYagDkdM9o0EwR71YKyByOXE+B4hp41S2FKKGAVGXrUNu7ckmB5tiLKt+J4SF25s4pKTkS2xW0F8RWIWHwYvWJTlHhlP1DKv5YtJDh/IrJqma3/RFu78Hj81jcW/eYuN98p4WAYHgNMyC4OFJz3D0QQVJ9W8w6fkHNOoIXd4THEMpQCqQLsoBac/INHpQXiYChOLhWVB108A0lvUc7nj/dCPN1poHF0SBHvRg=="
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-06:00" level=debug msg="completed challenge"
Jan 11 16:29:17 volumio go-librespot[10889]: time="2026-01-11T16:29:17-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 11 16:29:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:17 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:17 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:19 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.193.241
Jan 11 16:29:19 volumio avahi-daemon[664]: Registering new address record for 169.254.193.241 on eth0.IPv4.
Jan 11 16:29:19 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.193.241
Jan 11 16:29:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.193.241
Jan 11 16:29:20 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.193.241
Jan 11 16:29:20 volumio avahi-daemon[664]: Withdrawing address record for 169.254.193.241 on eth0.
Jan 11 16:29:20 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:20 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Jan 11 16:29:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:20 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:20 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:20 volumio go-librespot[10943]: go-librespot daemon starting...
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="app state loaded"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-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 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-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 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=info msg="zeroconf server listening on port 43705"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="obtained new client token: AADVTCT7CL35k9sVThi0lxCndwV+wlHkIgYMu8MgHA2WTIYCe44f6I70lnvIi4YgV4VAuRxL5x5UIIbI3Ch5PAi2z02MgArk+Q6jMV0XDWlvlqBwLHJ0Vg6gbaZgHwqU9RpJ4KqOcXYj66NjEUcYTghhmDpxg7Xnth6AXCfRnY1m+cp6CDfwPxgEhWQxr4RRiUoH281wmy6xdfLkf7hzmbCYuXfOhsOpaBG98aVmvLN/ZiBdoFy/fOAMLg=="
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=debug msg="completed challenge"
Jan 11 16:29:21 volumio go-librespot[10944]: time="2026-01-11T16:29:21-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:29:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:23 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:23 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Jan 11 16:29:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:24 volumio go-librespot[10954]: go-librespot daemon starting...
Jan 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29:24-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29:24-06:00" level=debug msg="app state loaded"
Jan 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29:24-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29: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 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29: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 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29: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 11 16:29:24 volumio go-librespot[10955]: time="2026-01-11T16:29:24-06:00" level=info msg="zeroconf server listening on port 39213"
Jan 11 16:29:25 volumio go-librespot[10955]: time="2026-01-11T16:29:25-06:00" level=debug msg="obtained new client token: AAAs4fADBVp+yL5JRu/QptomkK01b3IGh8242WoiA7bSuoo8WlSM4swz09R8ALtoqpCyUKMAVx/ynv4BWphcaFIMveTV89jZR57sdNDwJqXTVDcHDabFicUOfUeb/A7BXa3KpqqRqA7Mtr0505Kh98ySc0BDi/qCyrBz/Rp4pCx1oQiNGWOnOw6zulyqJgrpJUxCwpH/6rEBSDcXPPs3Neh5KAFrsriV9Db29ehNhhOpYlRWPH9vE6E="
Jan 11 16:29:25 volumio go-librespot[10955]: time="2026-01-11T16:29:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:25 volumio go-librespot[10955]: time="2026-01-11T16:29:25-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:25 volumio go-librespot[10955]: time="2026-01-11T16:29:25-06:00" level=debug msg="completed challenge"
Jan 11 16:29:25 volumio go-librespot[10955]: time="2026-01-11T16:29:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:29:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:25 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.116.101
Jan 11 16:29:25 volumio avahi-daemon[664]: Registering new address record for 169.254.116.101 on eth0.IPv4.
Jan 11 16:29:25 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.116.101
Jan 11 16:29:26 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.116.101
Jan 11 16:29:26 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.116.101
Jan 11 16:29:26 volumio avahi-daemon[664]: Withdrawing address record for 169.254.116.101 on eth0.
Jan 11 16:29:26 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:26 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:26 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:26 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:26 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:26 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:27 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Jan 11 16:29:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:28 volumio go-librespot[11008]: go-librespot daemon starting...
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=debug msg="app state loaded"
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-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 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-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 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-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 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=info msg="zeroconf server listening on port 43877"
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=debug msg="obtained new client token: AACHMn5YenOQNp4NOCgOVZtnxTpnsOn0Y/u2wHblKHwghcJdD5+fsCplWHf21za/BOLsyM9c3BuCLX6Ccz5UOpnSCZ+8EFIfGUoOzDqZUdVRpSWuf5Rchip1gGLqewtLJPCJcabPcopnPJcdnMJjf5wZMOVTdLy4X1LR4gj8EeqjBcb8oBfMlgLXdtMrk5+NItrJRaYcccvapPv8yKa9QpxUZKrSzsNLG0+RY8tzyOw5vwGbMORlwcoXRQ=="
Jan 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-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 11 16:29:28 volumio go-librespot[11009]: time="2026-01-11T16:29:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Jan 11 16:29:29 volumio go-librespot[11009]: time="2026-01-11T16:29:29-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:29 volumio go-librespot[11009]: time="2026-01-11T16:29:29-06:00" level=debug msg="completed challenge"
Jan 11 16:29:29 volumio go-librespot[11009]: time="2026-01-11T16:29:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 16:29:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:30 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:30 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:31 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.4.120
Jan 11 16:29:31 volumio avahi-daemon[664]: Registering new address record for 169.254.4.120 on eth0.IPv4.
Jan 11 16:29:31 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:31 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:31 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.4.120
Jan 11 16:29:32 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.4.120
Jan 11 16:29:32 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.4.120
Jan 11 16:29:32 volumio avahi-daemon[664]: Withdrawing address record for 169.254.4.120 on eth0.
Jan 11 16:29:32 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:32 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Jan 11 16:29:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:32 volumio go-librespot[11048]: go-librespot daemon starting...
Jan 11 16:29:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=debug msg="app state loaded"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-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 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-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 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-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 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=info msg="zeroconf server listening on port 40441"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=debug msg="obtained new client token: AACir/yNV4Ab0h+/KJLWvP024/oatYukR54vk9MvwD3q0xBE7oE0iAK4Pl/XZIAQZt/Tv0KlVcEnQmeKpBp8qiPL8GfG1DkIW68X9fgyQyHJChHauLjh0YBfLJTJWPSLuixM7CqdHY3QtB98BG4PxjemDpFSQWhjT3iCgNv6AVs3GLqqXGgGjpCg996zx0KEPi328T9nPW2dgBxyZIXkPpLcEg33G8gzJTCIrU2vvMgRMENlWXRLtUL/uA=="
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29:32-06:00" level=debug msg="completed challenge"
Jan 11 16:29:32 volumio go-librespot[11049]: time="2026-01-11T16:29: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 11 16:29:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:33 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:33 volumio volumio[9436]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Jan 11 16:29:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:35 volumio go-librespot[11058]: go-librespot daemon starting...
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="app state loaded"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:36 volumio volumio[9436]: info: Initializing connection to go-librespot Websocket
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="new websocket client"
Jan 11 16:29:36 volumio volumio[9436]: info: Connection to go-librespot Websocket established
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-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 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-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 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=info msg="zeroconf server listening on port 34599"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="obtained new client token: AABEh8L3fmHsSu4lF6wZgmbVJQRq6FZkiBJq+KB5Z6Wqjtv0b7GuExPuAumWoUUVV7mh/FmSq74iHa3KCgopcPnoyRA7jHeXedI4dhfeyMfP5bh5GteTW8JgblFiAXSBtqoO9TLH34UPYpVV3urQEuOU2KeIlTGWXbaBpLfr/GDWFheF1lnebPOUdIw+caW9f5ucpZMopsYlMwZRN91bc79X07xymeHWTJNq4xDiTymH8op4jvmT+El9zw=="
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="completed keyexchange"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-06:00" level=debug msg="completed challenge"
Jan 11 16:29:36 volumio go-librespot[11059]: time="2026-01-11T16:29:36-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 11 16:29:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 16:29:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 16:29:36 volumio volumio[9436]: info: Connection to go-librespot Websocket closed
Jan 11 16:29:36 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.78.68
Jan 11 16:29:36 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 16:29:36 volumio avahi-daemon[664]: Registering new address record for 169.254.78.68 on eth0.IPv4.
Jan 11 16:29:36 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:36 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:36 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:37 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.78.68
Jan 11 16:29:38 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.78.68
Jan 11 16:29:38 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.78.68
Jan 11 16:29:38 volumio avahi-daemon[664]: Withdrawing address record for 169.254.78.68 on eth0.
Jan 11 16:29:38 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 16:29:38 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:38 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 16:29:38 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 16:29:38 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 16:29:39 volumio volumio[9436]: info: Getting Spotify volume
Jan 11 16:29:39 volumio volumio[9436]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 11 16:29:39 volumio volumio[9436]: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 16:29:39 volumio volumio[9436]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Jan 11 16:29:39 volumio volumio[9436]: errno: -111,
Jan 11 16:29:39 volumio volumio[9436]: code: 'ECONNREFUSED',
Jan 11 16:29:39 volumio volumio[9436]: syscall: 'connect',
Jan 11 16:29:39 volumio volumio[9436]: address: '127.0.0.1',
Jan 11 16:29:39 volumio volumio[9436]: port: 9879,
Jan 11 16:29:39 volumio volumio[9436]: response: undefined
Jan 11 16:29:39 volumio volumio[9436]: }
Jan 11 16:29:39 volumio volumio[9436]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 11 16:29:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Jan 11 16:29:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 16:29:39 volumio go-librespot[11125]: go-librespot daemon starting...
Jan 11 16:29:39 volumio go-librespot[11126]: time="2026-01-11T16:29:39-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 16:29:39 volumio go-librespot[11126]: time="2026-01-11T16:29:39-06:00" level=debug msg="app state loaded"
Jan 11 16:29:39 volumio go-librespot[11126]: time="2026-01-11T16:29:39-06:00" level=debug msg="stored credentials not found"
Jan 11 16:29:39 volumio go-librespot[11126]: time="2026-01-11T16:29:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 16:29:39 volumio sudo[11134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 16:28'
Jan 11 16:29:39 volumio sudo[11134]: 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"