Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="app state loaded" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-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 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-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 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=info msg="zeroconf server listening on port 36395" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="obtained new client token: AAAdtD4m1ynf6T8Q3YHd3puttECtQx0WKGKQyZBISl99pRnFqt6canD+FxCOMtU+Z86hhPdr0Ty69wjA4UpLYB2x3T/HXLFQSn3qunbHXAtkQAJ5nLaSxfZnyMKPEH+7R9Xf5NLItdSl929mRSteVv7XtRxyhH1rqJdRSVO94Txy9ctN939XP91pwLv9WXsTi2brgSaXiYmQH6+PDxmvpbQNTvZwY1MUTiq9+M3PRfxZtEoy0n0DvwLr4A==" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=debug msg="completed challenge" Jan 11 22:46:00 volumio go-librespot[5456]: time="2026-01-11T22:46:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:01 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.75.212 Jan 11 22:46:01 volumio avahi-daemon[664]: Registering new address record for 169.254.75.212 on eth0.IPv4. Jan 11 22:46:01 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:01 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:01 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:01 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:02 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:02 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.75.212 Jan 11 22:46:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.75.212 Jan 11 22:46:02 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.75.212 Jan 11 22:46:02 volumio avahi-daemon[664]: Withdrawing address record for 169.254.75.212 on eth0. Jan 11 22:46:02 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:02 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Jan 11 22:46:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:03 volumio go-librespot[5510]: go-librespot daemon starting... Jan 11 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-06:00" level=debug msg="app state loaded" Jan 11 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-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 22:46:03 volumio go-librespot[5511]: time="2026-01-11T22:46:03-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 22:46:04 volumio go-librespot[5511]: time="2026-01-11T22:46:04-06:00" level=info msg="zeroconf server listening on port 46603" Jan 11 22:46:04 volumio go-librespot[5511]: time="2026-01-11T22:46:04-06:00" level=debug msg="obtained new client token: AAD9MUk0HMXIZHV1cxA8lSw8W/1ZoDk6awd50tJnlPzgxm2inRfhSiAL5LgmKBFSHANF11sbX9RINLw7Obu+YUNA6NH3Y+2eoWCZ1Q6DqlQiJDh/JFiNC51yPOznxcnhiEevXYZ6f/LTh04S6tiUOBnAvaK43C7a6so5W+Kj/CnljJ9jUJihIv/wRZhmrE16o/NdfsVsH0S6Egj0NgqtJ28JyGcCx74e0NqXp8aLDaFimgCXO2gqbPE=" Jan 11 22:46:04 volumio go-librespot[5511]: time="2026-01-11T22:46:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:04 volumio go-librespot[5511]: time="2026-01-11T22:46:04-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:04 volumio go-librespot[5511]: time="2026-01-11T22:46:04-06:00" level=debug msg="completed challenge" Jan 11 22:46:04 volumio go-librespot[5511]: time="2026-01-11T22:46:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:05 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:05 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:06 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.83.65 Jan 11 22:46:06 volumio avahi-daemon[664]: Registering new address record for 169.254.83.65 on eth0.IPv4. Jan 11 22:46:06 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:06 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:06 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:06 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:07 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.83.65 Jan 11 22:46:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Jan 11 22:46:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:07 volumio go-librespot[5535]: go-librespot daemon starting... Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="app state loaded" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:07 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.83.65 Jan 11 22:46:07 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.83.65 Jan 11 22:46:07 volumio avahi-daemon[664]: Withdrawing address record for 169.254.83.65 on eth0. Jan 11 22:46:07 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:07 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:07 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:07 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:07 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-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 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-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 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=info msg="zeroconf server listening on port 39399" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="obtained new client token: AAByO65+O4WIhpHMIqChibO5+Mq8F8mH6qRx7BgpEBaNStHzZxJcfiZ9/pq26QsGma88fnxJprNISqsg/bRF81p8fFRGVd/a06KV3GBZi07bmB8owL/MBVH5yURjDhsYlH446XaW6Pc+yE5TZHE8csecrSVcOnyWe8MIiP01KdrolyQNMFwH0IBdvD/WC4JKKmSgxd889qsA64xFYd8Hup8dOwOelCxWnSvRAgzar4FAD5bV/JhI1UmPCw==" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:07 volumio go-librespot[5536]: time="2026-01-11T22:46:07-06:00" level=debug msg="completed challenge" Jan 11 22:46:08 volumio go-librespot[5536]: time="2026-01-11T22:46:08-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 22:46:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:08 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:08 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:11 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:11 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Jan 11 22:46:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:11 volumio go-librespot[5577]: go-librespot daemon starting... Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=debug msg="app state loaded" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-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 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-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 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-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 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=info msg="zeroconf server listening on port 38347" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=debug msg="obtained new client token: AAAsMZbUeSVERh5C/mKU78MbYSN79mKuP55B27Z0XZk/+jR655DxLh5/rD/ykG3138HLGlj1+DoEkKhu2hFeCnL9iP5lYBkWBK+0ZMnyU1CvWec1Fv4ah5Bd+Q0AgiPKp3XrLGBiMUIdHIQm1UlDPpHQu57acDT5BygVwkNUsR/zQOzbJdS3RMc8PIgJ13cC70cSgZQWBVNZsrz1tzYZVQgn3EQATwEA2w4+CxNl+p+fmwbvdKQgXkPUdg==" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-06:00" level=debug msg="completed challenge" Jan 11 22:46:11 volumio go-librespot[5578]: time="2026-01-11T22:46:11-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 22:46:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.228.214 Jan 11 22:46:13 volumio avahi-daemon[664]: Registering new address record for 169.254.228.214 on eth0.IPv4. Jan 11 22:46:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.228.214 Jan 11 22:46:14 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:14 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.228.214 Jan 11 22:46:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.228.214 Jan 11 22:46:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.228.214 on eth0. Jan 11 22:46:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Jan 11 22:46:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:14 volumio go-librespot[5619]: go-librespot daemon starting... Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=debug msg="app state loaded" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-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 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-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 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-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 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=info msg="zeroconf server listening on port 36481" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=debug msg="obtained new client token: AAAMoLIDC9UvyxM7kgQ1ZCIt8SycJGnlvWfQH1ZeyIBtHeBtm3ctZr3NVYErw3NLlFhuM6MoMqQSaIgBO/ksBUwA+LhCYRBm1mD0zHY8UwHEQC7dcH5azYTuUEjMU3DzIOCusentXipnWO/pTH90aoJz35cueWRFq+3SCH7UjVRXHYujcZ3pYicRi055LdgroqIS5Hh8Wi9ShBjc3awGHxcovdPXpqX8YG3R9x455G06wNiJ3uIdljDgMw==" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-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 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-06:00" level=debug msg="completed challenge" Jan 11 22:46:15 volumio go-librespot[5620]: time="2026-01-11T22:46:15-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 22:46:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:17 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:17 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Jan 11 22:46:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:18 volumio go-librespot[5627]: go-librespot daemon starting... Jan 11 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-06:00" level=debug msg="app state loaded" Jan 11 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-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 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-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 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-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 22:46:18 volumio go-librespot[5628]: time="2026-01-11T22:46:18-06:00" level=info msg="zeroconf server listening on port 46577" Jan 11 22:46:19 volumio go-librespot[5628]: time="2026-01-11T22:46:19-06:00" level=debug msg="obtained new client token: AAA0rkdlGV6RwQTbohOdWrZKnwdyMelNjVHK7QNQUfxGXJlSVBlXdRFw/+iELRWNnx2bHGNji/2unu5P07sqbhdfbv3kQQAPt+KKZGHEcQPbPfrxQ3m0hP7bWq71rBux+XGRVoK0JwcY7JhwSd96IgpjeSmkSiDRedGBzUQsNk88ergvSGADFhCvLrkua57XcJlyvp6elGT/+Q1vQagKj8w36zq2qwdI3kUFDni9edBp7yUS8eOOLQc=" Jan 11 22:46:19 volumio go-librespot[5628]: time="2026-01-11T22:46:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:19 volumio go-librespot[5628]: time="2026-01-11T22:46:19-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:19 volumio go-librespot[5628]: time="2026-01-11T22:46:19-06:00" level=debug msg="completed challenge" Jan 11 22:46:19 volumio go-librespot[5628]: time="2026-01-11T22:46:19-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 22:46:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:19 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.93.81 Jan 11 22:46:19 volumio avahi-daemon[664]: Registering new address record for 169.254.93.81 on eth0.IPv4. Jan 11 22:46:19 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.93.81 Jan 11 22:46:20 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.93.81 Jan 11 22:46:20 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.93.81 Jan 11 22:46:20 volumio avahi-daemon[664]: Withdrawing address record for 169.254.93.81 on eth0. Jan 11 22:46:20 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:20 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:20 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Jan 11 22:46:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:22 volumio go-librespot[5683]: go-librespot daemon starting... Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=debug msg="app state loaded" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-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 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-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 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-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 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=info msg="zeroconf server listening on port 40043" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=debug msg="obtained new client token: AADEFf1tNwqLBtm3zukIbywcubzbaJed63a0AuMjILHap4xJx0ZzM+eisz8D28NNmpVu6gTiHrVIaNqlU2zYplM7sYeuIX4tzrJdB/uDG6IwwXzwfHBg/wAkyJlbqCibbG04MGfI5hzDhQ7lAeAnE8dElUJ4Y5QwN2o8qWAAlW+bOYnOKxXv8vzv3z9bLlXEcSrUhOrnWe1KeEXiFbSvXXSVS2njp3BkRSKUyRLT2GsF+WcUYXFiStt2zg==" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-06:00" level=debug msg="completed challenge" Jan 11 22:46:22 volumio go-librespot[5684]: time="2026-01-11T22:46:22-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 22:46:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:23 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:23 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:25 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.224.27 Jan 11 22:46:25 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:25 volumio avahi-daemon[664]: Registering new address record for 169.254.224.27 on eth0.IPv4. Jan 11 22:46:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.224.27 Jan 11 22:46:26 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:26 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Jan 11 22:46:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:26 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.224.27 Jan 11 22:46:26 volumio go-librespot[5707]: go-librespot daemon starting... Jan 11 22:46:26 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.224.27 Jan 11 22:46:26 volumio avahi-daemon[664]: Withdrawing address record for 169.254.224.27 on eth0. Jan 11 22:46:26 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=debug msg="app state loaded" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:26 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:26 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:26 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:26 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-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 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-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 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-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 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=info msg="zeroconf server listening on port 46359" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=debug msg="obtained new client token: AABkPdJORLW8Jnw3eaOHdKFSfSPvULKmHpoSeA5+SFOK9qGMDrp4fco3vEDPuAdJHviViyzGQfQCbrJcvplN+ADC8iwIUfe0S+sK2LRYsBHlWhb+EPnf4gpkMR0gGIH1pVqaPLlCOa97jLCtOyCr28bS/RTgU6ke2xhk8whqb85A5ZN+z5O95vEY4j74QtPhz0YzzV2uYlYe4V+NC3XqQpziV4KpGwBoVHorMl9HDJdDisguSKud1ssw2A==" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-06:00" level=debug msg="completed challenge" Jan 11 22:46:26 volumio go-librespot[5708]: time="2026-01-11T22:46:26-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 22:46:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:29 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:29 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Jan 11 22:46:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:29 volumio go-librespot[5735]: go-librespot daemon starting... Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=debug msg="app state loaded" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-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 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-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 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-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 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=info msg="zeroconf server listening on port 41319" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=debug msg="obtained new client token: AAAI7em2+8CRah8EHkteWk6lWZ0JY20MVGkAy95zCNgtvxmLTmlNkvwtaslxh1WCzllvgyxQcbpjAvysozkbfYOJwoWFRORJFvfbsudeYC82NRBNRZjlD5gVUAtRmliWrHbFNSV20Exm5IygvM2PU3rNGa+/LxLfU9Bi39QqxJ6AxKyL2dKbCpNppOoo11+fsUst7vBjghd0mp8M9UHH5zsh+UmkJBEFnLJ/LGSQ2zttsZUYCeBbFvEz0A==" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-06:00" level=debug msg="completed challenge" Jan 11 22:46:30 volumio go-librespot[5736]: time="2026-01-11T22:46:30-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 22:46:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:30 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.22.58 Jan 11 22:46:30 volumio avahi-daemon[664]: Registering new address record for 169.254.22.58 on eth0.IPv4. Jan 11 22:46:30 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:30 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:30 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:30 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.22.58 Jan 11 22:46:31 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.22.58 Jan 11 22:46:31 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.22.58 Jan 11 22:46:31 volumio avahi-daemon[664]: Withdrawing address record for 169.254.22.58 on eth0. Jan 11 22:46:31 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:31 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:31 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:31 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:31 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:32 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:32 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Jan 11 22:46:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:33 volumio go-librespot[5789]: go-librespot daemon starting... Jan 11 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-06:00" level=debug msg="app state loaded" Jan 11 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-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 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-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 22:46:33 volumio go-librespot[5790]: time="2026-01-11T22:46:33-06:00" level=info msg="zeroconf server listening on port 40873" Jan 11 22:46:34 volumio go-librespot[5790]: time="2026-01-11T22:46:34-06:00" level=debug msg="obtained new client token: AADnZsMT1eVLFbLbnl1z9B5iSCzGQfwxz/qVoSgthiEISwCF3AT85iKJSecdZLPNN2ESI2oRjyyDeJfThhnwVKhPo3YpfvSfo3AtTSFF3voPl2zDXhMXuBcUI86k+KzLKYetwAoa6jmcUg+0olH4w3kdlpYeMSjsSMxTxJcGKP6SXpxJhgAuXOZVjhDZGidbwn2Gk8OBG/EzaycfshuYPAAl6S08NsZMBn9Vw8HpaVWEShHvbugDv1c=" Jan 11 22:46:34 volumio go-librespot[5790]: time="2026-01-11T22:46:34-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:34 volumio go-librespot[5790]: time="2026-01-11T22:46:34-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:34 volumio go-librespot[5790]: time="2026-01-11T22:46:34-06:00" level=debug msg="completed challenge" Jan 11 22:46:34 volumio go-librespot[5790]: time="2026-01-11T22:46:34-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 22:46:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:35 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:35 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:36 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.214.140 Jan 11 22:46:36 volumio avahi-daemon[664]: Registering new address record for 169.254.214.140 on eth0.IPv4. Jan 11 22:46:36 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:36 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:36 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:36 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:37 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.214.140 Jan 11 22:46:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Jan 11 22:46:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:37 volumio go-librespot[5813]: go-librespot daemon starting... Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=debug msg="app state loaded" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-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 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-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 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-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 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=info msg="zeroconf server listening on port 39127" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=debug msg="obtained new client token: AACTUnZhhV1LOAu1xW5NXP+S91xP0rPvroGzvuyGGUMTwom0CJDh2QZBdIpKB2DLF6lwhEFbdiCLg3yVt7AR4acIVV1dDhzeQl5eOVwUIgpCg4fV1YrX6u0/acqG/pE83oRvOlbYZNq8tvFyJuhvtCPsmRuErg1vWfKo0Q6gH8JsMKpZLQIcPU9z4sAMRD/mynl3lYg672ljcboseePOP6vMRdQuM8Pjofg89MhuQn9TuBbwaJXrH8v2tg==" Jan 11 22:46:37 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.214.140 Jan 11 22:46:37 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.214.140 Jan 11 22:46:37 volumio avahi-daemon[664]: Withdrawing address record for 169.254.214.140 on eth0. Jan 11 22:46:37 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:37 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:37 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:37 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:37 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=debug msg="completed challenge" Jan 11 22:46:37 volumio go-librespot[5814]: time="2026-01-11T22:46:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:38 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:38 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Jan 11 22:46:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:41 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:41 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:41 volumio go-librespot[5852]: go-librespot daemon starting... Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=debug msg="app state loaded" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-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 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-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 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-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 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=info msg="zeroconf server listening on port 43287" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=debug msg="obtained new client token: AACW3Tl1bHnlkdjzQM0TQHhm0bkd+yzD0klnLUrc2LYB3cYAH8hYwHn78nG/Vjixpxk87qLBP1eMSxByt4nP/GRuzuLQxwFCtqHs6aF8D1874uos0aXYSMZ4gRAmX43296KiIQUWjgzoodeIKimasr7+0d/KhDscbeHo50u+dnlJe47vzuQqh8vjLRFJ4HHLYG9o1jgiXp79ySInODgPfhLTW2ZlyHbzy5lthi7Cf0jkaWAogMgsHxTMHw==" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-06:00" level=debug msg="completed challenge" Jan 11 22:46:41 volumio go-librespot[5853]: time="2026-01-11T22:46:41-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 22:46:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:42 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.70.60 Jan 11 22:46:42 volumio avahi-daemon[664]: Registering new address record for 169.254.70.60 on eth0.IPv4. Jan 11 22:46:42 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:42 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:42 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:42 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:42 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.70.60 Jan 11 22:46:42 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.70.60 Jan 11 22:46:42 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.70.60 Jan 11 22:46:42 volumio avahi-daemon[664]: Withdrawing address record for 169.254.70.60 on eth0. Jan 11 22:46:42 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:42 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:42 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:42 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:42 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:44 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:44 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Jan 11 22:46:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:44 volumio go-librespot[5892]: go-librespot daemon starting... Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=debug msg="app state loaded" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-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 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-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 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-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 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=info msg="zeroconf server listening on port 35901" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=debug msg="obtained new client token: AAC0Hudw+NH/Tzzo69/Nze+/i4VWusNefdoQFkoN3M+gvo1WFm3Kjgg5AZm6NeZf07h34PYmpNdU1GmydMoUAgzcC10rpzFYxeqtUqiIWcjvvrwcnIxPWTp/d0hJEXKVtoeiAgWB2/jBa6Rhgi9QXaZ/P180TNb938/qPVsh3Dvts3kUc6PExmAi0eg1gUSJTLxB653hocZ/DZJdkkig9Nga5TxMoA950Mqny8D+SOaVhRVxX4SOJ6RFLw==" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=debug msg="completed challenge" Jan 11 22:46:45 volumio go-librespot[5893]: time="2026-01-11T22:46:45-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:46 volumio nmbd[1048]: [2026/01/11 22:46:46.184465, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 11 22:46:46 volumio nmbd[1048]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.221 for name WORKGROUP<1d>. Jan 11 22:46:46 volumio nmbd[1048]: This response was from IP 192.168.1.112, reporting an IP address of 192.168.1.112. Jan 11 22:46:47 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:47 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:48 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.247.151 Jan 11 22:46:48 volumio avahi-daemon[664]: Registering new address record for 169.254.247.151 on eth0.IPv4. Jan 11 22:46:48 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:48 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:48 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:48 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Jan 11 22:46:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:48 volumio go-librespot[5916]: go-librespot daemon starting... Jan 11 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-06:00" level=debug msg="app state loaded" Jan 11 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-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 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-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 22:46:48 volumio go-librespot[5917]: time="2026-01-11T22:46:48-06:00" level=info msg="zeroconf server listening on port 37627" Jan 11 22:46:49 volumio go-librespot[5917]: time="2026-01-11T22:46:49-06:00" level=debug msg="obtained new client token: AAAKMBpaawPmoTRPZROqV5C3bDTeQuFNtQasNbt2PutQ8zqh+lvKpJ2+MAVr5Qfibc1/NALUyMs7f1reGmDbDetTCC1Bi/8870aCVQNtdUK8HvfNA+qKHyNiej2mpurP5B9Hsj7siiucW3ghb4VinKo4GBxHI+NOFsi8KMWEzvw2Yaa4o88RA2S9FxioNJFk50dcHNZ3xoU+8+og4Ba4WBNGRQQJW7U/qX+F0AxV1BT+gu9YZDgFuGs=" Jan 11 22:46:49 volumio go-librespot[5917]: time="2026-01-11T22:46:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:49 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.247.151 Jan 11 22:46:49 volumio go-librespot[5917]: time="2026-01-11T22:46:49-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:49 volumio go-librespot[5917]: time="2026-01-11T22:46:49-06:00" level=debug msg="completed challenge" Jan 11 22:46:49 volumio go-librespot[5917]: time="2026-01-11T22:46:49-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:49 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.247.151 Jan 11 22:46:49 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.247.151 Jan 11 22:46:49 volumio avahi-daemon[664]: Withdrawing address record for 169.254.247.151 on eth0. Jan 11 22:46:49 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:49 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:49 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:49 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:49 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:50 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:50 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Jan 11 22:46:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:52 volumio go-librespot[5957]: go-librespot daemon starting... Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="app state loaded" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-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 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-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 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=info msg="zeroconf server listening on port 38913" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="obtained new client token: AABgRQUjTTBMd/Qh4/j/mse8spBlWHYV4zcItTm9do+MHTkIvb+q7VvzHZ43bTUzgqPUYoJ3wXgCAgs3cOEh9dCDvXq7UlYRi0SeZMYAHnrQQx3Y2+PdU4spDCEfCIkzORLlEq0yVe29V6KEJTJNSv72EuMG3kdpE0hBuxzMoCSSZpXgu2ixT1LmUCO1LIQURDVpbYtoW3AL0uDDSW7eiT4ufPpUHm/cm3pXEUJuG9RY/Qj+cJsSfaVBcQ==" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=debug msg="completed challenge" Jan 11 22:46:52 volumio go-librespot[5958]: time="2026-01-11T22:46:52-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:53 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:53 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:54 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.143.153 Jan 11 22:46:54 volumio avahi-daemon[664]: Registering new address record for 169.254.143.153 on eth0.IPv4. Jan 11 22:46:54 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:54 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:54 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:54 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:54 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.143.153 Jan 11 22:46:55 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.143.153 Jan 11 22:46:55 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.143.153 Jan 11 22:46:55 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:46:55 volumio avahi-daemon[664]: Withdrawing address record for 169.254.143.153 on eth0. Jan 11 22:46:55 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:55 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:55 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:55 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:46:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Jan 11 22:46:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:56 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:56 volumio go-librespot[5997]: go-librespot daemon starting... Jan 11 22:46:56 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="app state loaded" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="stored credentials not found" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-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 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-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 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=info msg="zeroconf server listening on port 39583" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="obtained new client token: AAAbg3F37lNdYMVrUvwpkCGER5N4cGAFbpmIzs4PT3+a7NoN0d3Kivww3mggd9UdgewV7nR4SVLWLa4HCll7MmILDfjthNjZBfT3GF8CoUSxOvlIBSuRoQ0WD6UHJ271LhfrGT7mfpy4akIp9Wml8whrI4P1zjyskZ8hakvQHslu77Lb0Wrw0Zuys0L0enoYGVXvhcRpxQYHKDH9MarEM/f9IBgHJSNAEp3WzgNz7fR2kvl4AeItme5IdA==" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="completed keyexchange" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=debug msg="completed challenge" Jan 11 22:46:56 volumio go-librespot[5998]: time="2026-01-11T22:46:56-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:46:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:46:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:46:59 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:46:59 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:46:59 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.65.89 Jan 11 22:46:59 volumio avahi-daemon[664]: Registering new address record for 169.254.65.89 on eth0.IPv4. Jan 11 22:46:59 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:46:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Jan 11 22:46:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:46:59 volumio go-librespot[6021]: go-librespot daemon starting... Jan 11 22:46:59 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:46:59 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:46:59 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="app state loaded" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="stored credentials not found" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:47:00 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.65.89 Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=info msg="zeroconf server listening on port 34555" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="obtained new client token: AADtCh38f2d4xmYj8L8B987xVA7NwGjG7LbIDYZh1blIeKy1b2QcdvT5OYAf8tYvfmlLxbp+cAMm7WXukLwTCEIaoazk61dpH8HzZwxl17LSbznJ9Ynl4qngY8M3jmNn1RgnRoDCCa3xTBslalAhon5cMt2ifxnreqcdbTLkndju1iEQT0bVCjHTXgoUmYdX/uOBNCKiVygl7TvC9VVfcvZqfG8H03v4K+qrMXZQPBst/QPRrz60lf4hAA==" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="completed keyexchange" Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=debug msg="completed challenge" Jan 11 22:47:00 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.65.89 Jan 11 22:47:00 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.65.89 Jan 11 22:47:00 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:47:00 volumio avahi-daemon[664]: Withdrawing address record for 169.254.65.89 on eth0. Jan 11 22:47:00 volumio go-librespot[6022]: time="2026-01-11T22:47:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:47:00 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:47:00 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:00 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:47:00 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:47:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:47:02 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:47:02 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:47:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Jan 11 22:47:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:03 volumio go-librespot[6060]: go-librespot daemon starting... Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=debug msg="app state loaded" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=debug msg="stored credentials not found" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 22:47:03 volumio go-librespot[6061]: time="2026-01-11T22:47:03-06:00" level=info msg="zeroconf server listening on port 43881" Jan 11 22:47:04 volumio go-librespot[6061]: time="2026-01-11T22:47:04-06:00" level=debug msg="obtained new client token: AAD4onmBm2wbTUnFDJGT3hRn5MZxwi5AEv0ChZOaV86V0AT9YJMTuygkg6X3pLM85tSiHd3HfaSygWpaAj3BbV31w8ueMK5IPK36wV88lj9PXoMw01g8eVUHU+u9h/udoYSmcBLwE7QHmRqZagcTBZkn8G2UGsZwUYQ/TVaQjy2TRHN5zo9TZl5YeApGh6JUGPgYzQqadXiyUj6bvfHR5ad77q22NE7WIdxqT5FEjtrXzgLwoQLoWqo=" Jan 11 22:47:04 volumio go-librespot[6061]: time="2026-01-11T22:47:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:47:04 volumio go-librespot[6061]: time="2026-01-11T22:47:04-06:00" level=debug msg="completed keyexchange" Jan 11 22:47:04 volumio go-librespot[6061]: time="2026-01-11T22:47:04-06:00" level=debug msg="completed challenge" Jan 11 22:47:04 volumio go-librespot[6061]: time="2026-01-11T22:47:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:47:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:47:05 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:47:05 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:47:06 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.77.19 Jan 11 22:47:06 volumio avahi-daemon[664]: Registering new address record for 169.254.77.19 on eth0.IPv4. Jan 11 22:47:06 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:47:06 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:06 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:47:06 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:07 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.77.19 Jan 11 22:47:07 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.77.19 Jan 11 22:47:07 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.77.19 Jan 11 22:47:07 volumio avahi-daemon[664]: Withdrawing address record for 169.254.77.19 on eth0. Jan 11 22:47:07 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:47:07 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Jan 11 22:47:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:07 volumio go-librespot[6100]: go-librespot daemon starting... Jan 11 22:47:07 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:07 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:47:07 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="app state loaded" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="stored credentials not found" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-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 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-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 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=info msg="zeroconf server listening on port 40825" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="obtained new client token: AAB/3OFo7jgw2Y5jutx9xGxvZZ3lBONyaVeuvSJIldxDewN0KO2ukfM6kcdvvZ0Bs+6T4/SwUsbcuFW1tx3yhwL37etg+S2PyGpHKEYt1YqMf4j50HgOj1izqvK0lGwLXgZvXfpvZEKKItS9fqj3DAYCrL/W4ezeonVqsdG+U/qsyKDmoth/fbt7hYdogGST/3aeuDKxAoC5jZIOwA7gPwX87IqHsfjpgnLwn3wDtGJonLR1yiJ78jfPtA==" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="completed keyexchange" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=debug msg="completed challenge" Jan 11 22:47:07 volumio go-librespot[6101]: time="2026-01-11T22:47:07-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Jan 11 22:47:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:47:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:47:08 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:47:08 volumio volumio[4101]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:47:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Jan 11 22:47:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:10 volumio go-librespot[6125]: go-librespot daemon starting... Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="app state loaded" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="stored credentials not found" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-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 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-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 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-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 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=info msg="zeroconf server listening on port 40641" Jan 11 22:47:11 volumio volumio[4101]: info: Initializing connection to go-librespot Websocket Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="new websocket client" Jan 11 22:47:11 volumio volumio[4101]: info: Connection to go-librespot Websocket established Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="obtained new client token: AADICaf2woondVv3A9GpnkUDoQV9gh/WDklbc7ffEYU019E/nbRsADh1Xl5VK/bENeUfnI2vu8mqygoOaD3dU2+2twM78yp3kqmdvFyGd3K7k1PvA1tSCvinRICFWIPETt1Nv5EuOL0LmV0Na4mYRKVwYFH7ds+/DxJuIWBq+Rfp92TV7W3cfY+3jnXp1e9hW+sP2A94JnSKqPiWsi214BbgO8OEQdVSRsB3XLY6Uc/zln2cVo9VkqmAjA==" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="completed keyexchange" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-06:00" level=debug msg="completed challenge" Jan 11 22:47:11 volumio go-librespot[6126]: time="2026-01-11T22:47:11-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 22:47:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 22:47:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 22:47:11 volumio volumio[4101]: info: Connection to go-librespot Websocket closed Jan 11 22:47:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.244.20 Jan 11 22:47:13 volumio avahi-daemon[664]: Registering new address record for 169.254.244.20 on eth0.IPv4. Jan 11 22:47:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16 Jan 11 22:47:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:47:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.244.20 Jan 11 22:47:13 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.244.20 Jan 11 22:47:13 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.244.20 Jan 11 22:47:13 volumio avahi-daemon[664]: Withdrawing address record for 169.254.244.20 on eth0. Jan 11 22:47:13 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16 Jan 11 22:47:13 volumio dhcpcd[684]: eth0: probing for an IPv4LL address Jan 11 22:47:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 22:47:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 22:47:14 volumio volumio[4101]: info: Getting Spotify volume Jan 11 22:47:14 volumio volumio[4101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 22:47:14 volumio volumio[4101]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 22:47:14 volumio volumio[4101]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 11 22:47:14 volumio volumio[4101]: errno: -111, Jan 11 22:47:14 volumio volumio[4101]: code: 'ECONNREFUSED', Jan 11 22:47:14 volumio volumio[4101]: syscall: 'connect', Jan 11 22:47:14 volumio volumio[4101]: address: '127.0.0.1', Jan 11 22:47:14 volumio volumio[4101]: port: 9879, Jan 11 22:47:14 volumio volumio[4101]: response: undefined Jan 11 22:47:14 volumio volumio[4101]: } Jan 11 22:47:14 volumio volumio[4101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 22:47:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Jan 11 22:47:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 22:47:14 volumio go-librespot[6177]: go-librespot daemon starting... Jan 11 22:47:14 volumio go-librespot[6178]: time="2026-01-11T22:47:14-06:00" level=info msg="running go-librespot 0.4.0" Jan 11 22:47:14 volumio go-librespot[6178]: time="2026-01-11T22:47:14-06:00" level=debug msg="app state loaded" Jan 11 22:47:14 volumio go-librespot[6178]: time="2026-01-11T22:47:14-06:00" level=debug msg="stored credentials not found" Jan 11 22:47:14 volumio go-librespot[6178]: time="2026-01-11T22:47:14-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 22:47:15 volumio go-librespot[6178]: time="2026-01-11T22:47:15-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 22:47:15 volumio go-librespot[6178]: time="2026-01-11T22:47:15-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 22:47:15 volumio go-librespot[6178]: time="2026-01-11T22:47:15-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 22:47:15 volumio go-librespot[6178]: time="2026-01-11T22:47:15-06:00" level=info msg="zeroconf server listening on port 34205" Jan 11 22:47:15 volumio sudo[6187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 22:46' Jan 11 22:47:15 volumio sudo[6187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 22:47:15 volumio go-librespot[6178]: time="2026-01-11T22:47:15-06:00" level=debug msg="obtained new client token: AAB87Brz/UU7KiVVO7igQ+IkHY0zidTSFM3L+XnrHRfv+WzAc7sBq9XmJxaJJ/7E7ctigvYGvx1o9kLr3sYa6IuApOw86ZBMPjHZVpIPhPhXcKZinmJUYmU66Wa9pWLyjff1iBE6tsauvzpZURXBAeoYQgOQFc5N+APdzQYOVouASp6EsuhHBJF7Eiz09WOFNxD2OHQIKJq5aVnNQVAwYsbAKvJkeP/SfsBIpvnowQV6O2ypyjm5f4o=" Jan 11 22:47:15 volumio go-librespot[6178]: time="2026-01-11T22:47:15-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" 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"