Jan 11 20:18:00 volumio volumio[18174]: info: Updating MyVolumio device info
Jan 11 20:18:00 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:00 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:00 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:00 volumio volumio[18174]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 11 20:18:01 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:01 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:02 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.12.79
Jan 11 20:18:02 volumio avahi-daemon[664]: Registering new address record for 169.254.12.79 on eth0.IPv4.
Jan 11 20:18:02 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Jan 11 20:18:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:02 volumio go-librespot[19052]: go-librespot daemon starting...
Jan 11 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18:02-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18:02-06:00" level=debug msg="app state loaded"
Jan 11 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18:02-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18:02-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 20:18:02 volumio go-librespot[19053]: time="2026-01-11T20:18:02-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 20:18:03 volumio go-librespot[19053]: time="2026-01-11T20:18:03-06:00" level=info msg="zeroconf server listening on port 45455"
Jan 11 20:18:03 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.12.79
Jan 11 20:18:03 volumio go-librespot[19053]: time="2026-01-11T20:18:03-06:00" level=debug msg="obtained new client token: AADqc+pOa2IZH8g13kL1PnEkkKjdA5khc6mJz1hSAXOZpz3GOxLay3Doeu+P9muq0BRm2FS4cFIUqWKd6phtw47T62RzOAkapw1210q+uMFmRNQQgVUnj1/SZWiXlLXCQh/ulai3R8S7zvGcpLMvjSoCs+Q6A51yK8jlYbGm5EG/6eEA91HV+SDzLNROlATvRKP0FslzRcS1MILJI3kf1tom6pq8qjHwVGIPD7M89BbvaVk/frDGUl8="
Jan 11 20:18:03 volumio go-librespot[19053]: time="2026-01-11T20:18:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:03 volumio go-librespot[19053]: time="2026-01-11T20:18:03-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:03 volumio go-librespot[19053]: time="2026-01-11T20:18:03-06:00" level=debug msg="completed challenge"
Jan 11 20:18:03 volumio go-librespot[19053]: time="2026-01-11T20:18:03-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:18:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:03 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.12.79
Jan 11 20:18:03 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.12.79
Jan 11 20:18:03 volumio avahi-daemon[664]: Withdrawing address record for 169.254.12.79 on eth0.
Jan 11 20:18:03 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:03 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:03 volumio volumio[18174]: info: MYVOLUMIO: Adding device
Jan 11 20:18:03 volumio volumio[18174]: info: MYVOLUMIO: Evaluating Server
Jan 11 20:18:04 volumio volumio[18174]: info: Setting Geolocation for MyVolumio to us1
Jan 11 20:18:04 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:04 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:04 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:04 volumio volumio[18174]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 11 20:18:04 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:04 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:05 volumio volumio[18174]: info: Updating MyVolumio device info
Jan 11 20:18:05 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:05 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:05 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:05 volumio volumio[18174]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 11 20:18:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Jan 11 20:18:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:06 volumio go-librespot[19094]: go-librespot daemon starting...
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=debug msg="app state loaded"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-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 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-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 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=info msg="zeroconf server listening on port 44611"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=debug msg="obtained new client token: AAAX1YHpxJ0kOTQxiLxNiQm6D6NMq9FctM4/dtTIAK2+w92K9bx0lhjGbHm523ENIYwCQpBa1h+2iCdjzA1XUEc6TVqPVeUc2nUmetRFyp6zMo6mxok2bu1WJbmJLuWGAByrFlUhmvkFbJAVlF9iY2Iw/8Vjmo7Dl1IfP1FZTbLVVE4Ffq+Oj6jmyosNZ+ut8yjTzoz9Fp9z7hdO00nGDrWhsa9d+tELeRhKgc5LvIZE+qG5WW7t5cyrcw=="
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:06 volumio go-librespot[19095]: time="2026-01-11T20:18:06-06:00" level=debug msg="completed challenge"
Jan 11 20:18:07 volumio go-librespot[19095]: time="2026-01-11T20:18: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 20:18:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:07 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:07 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:08 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 20:18:08 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:08 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 11 20:18:08 volumio volumio-remote-updater[14193]: Test mode disabled
Jan 11 20:18:08 volumio volumio-remote-updater[14193]: Alpha mode disabled
Jan 11 20:18:08 volumio volumio-remote-updater[14193]: Alpha legacy test mode disabled
Jan 11 20:18:08 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.165.34
Jan 11 20:18:08 volumio avahi-daemon[664]: Registering new address record for 169.254.165.34 on eth0.IPv4.
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:08 volumio volumio[18174]: 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 20:18:08 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.165.34
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.165.34
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.165.34
Jan 11 20:18:08 volumio avahi-daemon[664]: Withdrawing address record for 169.254.165.34 on eth0.
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:08 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Jan 11 20:18:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:10 volumio go-librespot[19141]: go-librespot daemon starting...
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=debug msg="app state loaded"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18: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-gew1.spotify.com:80]"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-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 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-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 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=info msg="zeroconf server listening on port 35567"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=debug msg="obtained new client token: AACvNNibHUZWgL98MIYvmGHgl7R1EyVFykjREfAAGbikuZPpRsDLuYwbDh7N3uYRqZTTTU7YbiHsf1QFfgCdJgDUnpj2iL13qiZRtMzh/HLapvJbXn0+vwXQYQ1fJKe3R6CkJFHFWajwfXluF5QwjHWXIN6O+V9OQUvcxdWiqyGIU6nhdJKKtH8z1ymJfoLiMGLAJVHhffKashFD3kVYG1+RHt40u3DZH+WBAbSxVed3LoMKS7dcjMiPUw=="
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18:10-06:00" level=debug msg="completed challenge"
Jan 11 20:18:10 volumio go-librespot[19142]: time="2026-01-11T20:18: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 20:18:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:10 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:10 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:12 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 11 20:18:12 volumio volumio[18174]: 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 20:18:12 volumio volumio[18174]: info: CoreCommandRouter::volumioGetState
Jan 11 20:18:12 volumio volumio[18174]: info: CorePlayQueue::getTrack 0
Jan 11 20:18:13 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.193.88
Jan 11 20:18:13 volumio avahi-daemon[664]: Registering new address record for 169.254.193.88 on eth0.IPv4.
Jan 11 20:18:13 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:13 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:13 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Jan 11 20:18:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:14 volumio go-librespot[19179]: go-librespot daemon starting...
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="app state loaded"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=info msg="zeroconf server listening on port 38997"
Jan 11 20:18:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.193.88
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="obtained new client token: AAAVAXeqcfzMn/2vDZRT1nCcciuMWsJXpRMkFA6upoHzeKcDJJsw+jT35MnqeZTtffZOhDQj1xmxWGqNx+FTj2cW1KMkyyORGmbT68HbH89Hy7Wn/JZMFy0ybSHS8IpRvY0msCOhQAMTHrGkvs9yoDcYyfCUy74lZeKvoNmQHtMsl970IuE3JcZzJCHqCSMKP3Bt6XLXm9VQJL/Fzo3epN8/aAysDK29UrUSdxGwvRjnYqMcJVjm3z+HAw=="
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=debug msg="completed challenge"
Jan 11 20:18:14 volumio go-librespot[19180]: time="2026-01-11T20:18:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:18:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.193.88
Jan 11 20:18:14 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.193.88
Jan 11 20:18:14 volumio avahi-daemon[664]: Withdrawing address record for 169.254.193.88 on eth0.
Jan 11 20:18:14 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:14 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:16 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:16 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 11 20:18:17 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 11 20:18:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Jan 11 20:18:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:17 volumio go-librespot[19204]: go-librespot daemon starting...
Jan 11 20:18:17 volumio go-librespot[19205]: time="2026-01-11T20:18:17-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:17 volumio go-librespot[19205]: time="2026-01-11T20:18:17-06:00" level=debug msg="app state loaded"
Jan 11 20:18:17 volumio go-librespot[19205]: time="2026-01-11T20:18:17-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:17 volumio go-librespot[19205]: time="2026-01-11T20:18:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-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 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-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 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-06:00" level=info msg="zeroconf server listening on port 34071"
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-06:00" level=debug msg="obtained new client token: AADAw7R3V1SsYDJJ6H0rLL/zYIlYmkgM0gqAnAJKo0mdH2WutP0153RYRoTalWNpW9MvPFcCpc/RlxZM7QtwTnQNCvh3vt9tvIhSCGKrNzPjoH/gw18pvEz9yRorhK49AhLoK4NU02CnktrGZNp0Old+oxZOCCN2QHATPIKhJ4/Kxp9IhqzyQ8ri1yX6woc0cBI2+oRIOewiGvoEYbCJ1wYVSXj7T2r+NgMvwq2sO4q+/AsqcQhy2UI="
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:18 volumio volumio[18174]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 11 20:18:18 volumio volumio[18174]: info: Completed starting MyVolumio Plugin
Jan 11 20:18:18 volumio volumio[18174]: [Metrics] CommandRouter: 61s 88.22ms
Jan 11 20:18:18 volumio volumio[18174]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 11 20:18:18 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 11 20:18:18 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 11 20:18:18 volumio volumio[18174]: info: CoreCommandRouter::Close All Modals sent
Jan 11 20:18:18 volumio volumio[18174]: info: CoreCommandRouter::Close All Modals sent
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-06:00" level=debug msg="completed challenge"
Jan 11 20:18:18 volumio go-librespot[19205]: time="2026-01-11T20:18:18-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:18:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:19 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 11 20:18:19 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 11 20:18:19 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 11 20:18:19 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jan 11 20:18:19 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:19 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:20 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.29.1
Jan 11 20:18:20 volumio avahi-daemon[664]: Registering new address record for 169.254.29.1 on eth0.IPv4.
Jan 11 20:18:20 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:20 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:20 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.29.1
Jan 11 20:18:21 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.29.1
Jan 11 20:18:21 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.29.1
Jan 11 20:18:21 volumio avahi-daemon[664]: Withdrawing address record for 169.254.29.1 on eth0.
Jan 11 20:18:21 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:21 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Jan 11 20:18:21 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:21 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:21 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:21 volumio go-librespot[19265]: go-librespot daemon starting...
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=debug msg="app state loaded"
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18: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 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18: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 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18: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 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=info msg="zeroconf server listening on port 38737"
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=debug msg="obtained new client token: AAChufMSvhhPOMkrdXSp2UX2Z7HoMblxipqh4sqGNc8KdNHOi9hLOwxh3j/gOMVCbVrMZga2YopVrz9AtDiHm07XttfcUh2jVafjiPDDhtHwwzCLYVXqV8kvsd4+UCITo0iDgOR47J3dMyIg42QmLE5VLe9yGp+vaQTQd9UPWmte4zZZvKoPWuDsrl1WcKL/frnqq3WnxpBnr6QRLz4+8lIZljHH1MGGGpY/13j+UaxaQvMW8HBhHQyzrA=="
Jan 11 20:18:21 volumio go-librespot[19266]: time="2026-01-11T20:18:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:22 volumio go-librespot[19266]: time="2026-01-11T20:18:22-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:22 volumio go-librespot[19266]: time="2026-01-11T20:18:22-06:00" level=debug msg="completed challenge"
Jan 11 20:18:22 volumio go-librespot[19266]: time="2026-01-11T20:18: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 20:18:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:22 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:22 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Jan 11 20:18:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:25 volumio volumio[18174]: info: BOOT COMPLETED
Jan 11 20:18:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:25 volumio go-librespot[19273]: go-librespot daemon starting...
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="app state loaded"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=info msg="zeroconf server listening on port 36649"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="obtained new client token: AAC9svDTvI/CQy9o1SV677Z31WPlDlTvthyjEENmsielQoeN2w2XWkQGjzIxxCgzDfeZBMnuY5iHR5kpg5cST5UtxjEORAajkExDtgO33i0oDN2AoxMQe+JHY03ffau0/Qd44Vtg1tNRyw8QgoG4vxpMZUV3TTDPTx3Ts/NQgsMkDesVi/J1/TyBfXBo88n4AUMFzDkHEEkBea7TLduh+BSoxVngx0EV13VU21e9SQ5ElX6Igxzkflx37g=="
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18:25-06:00" level=debug msg="completed challenge"
Jan 11 20:18:25 volumio go-librespot[19274]: time="2026-01-11T20:18: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 20:18:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:25 volumio kernel: hwmon hwmon1: Voltage normalised
Jan 11 20:18:25 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:25 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:26 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.201.134
Jan 11 20:18:26 volumio avahi-daemon[664]: Registering new address record for 169.254.201.134 on eth0.IPv4.
Jan 11 20:18:26 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:26 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:26 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:26 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:27 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.201.134
Jan 11 20:18:27 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.201.134
Jan 11 20:18:27 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.201.134
Jan 11 20:18:27 volumio avahi-daemon[664]: Withdrawing address record for 169.254.201.134 on eth0.
Jan 11 20:18:27 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:28 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:28 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:28 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:28 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:28 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:28 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Jan 11 20:18:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:28 volumio go-librespot[19317]: go-librespot daemon starting...
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="app state loaded"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-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 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-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 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=info msg="zeroconf server listening on port 46719"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="obtained new client token: AADzd15ggHxQCwHuK2wm5T4u4iA4ZZJOLBs9V+i2UJvsyVIwniwNnA/0PRRfmDDaigmZlvXBaxeE88QrHgyb88iZ8y0IYh9gxhgk7GECynlZmqT0EnfAPPLhXnFqxxhoYkD2i+J4JbEHF3UESFROEXjXMpnJodfks3m1OlMCkacPLdFkk1/DRzkk5NRoE789MRL+pm5NtdPybzSva4ADb4MA8MvWasTmdyfsvLiX37KhJ99utpKk3G6n1w=="
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18:29-06:00" level=debug msg="completed challenge"
Jan 11 20:18:29 volumio go-librespot[19318]: time="2026-01-11T20:18: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 20:18:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:31 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:31 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Jan 11 20:18:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:32 volumio go-librespot[19339]: go-librespot daemon starting...
Jan 11 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18:32-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18:32-06:00" level=debug msg="app state loaded"
Jan 11 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18:32-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:32 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.65.210
Jan 11 20:18:32 volumio avahi-daemon[664]: Registering new address record for 169.254.65.210 on eth0.IPv4.
Jan 11 20:18:32 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18: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 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18: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 20:18:32 volumio go-librespot[19340]: time="2026-01-11T20:18: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 20:18:33 volumio go-librespot[19340]: time="2026-01-11T20:18:33-06:00" level=info msg="zeroconf server listening on port 40145"
Jan 11 20:18:33 volumio go-librespot[19340]: time="2026-01-11T20:18:33-06:00" level=debug msg="obtained new client token: AADT44X1IkOSbJCaXiH+n538+vc9mOZ6MmP+WVV424Nrg+l5wPIJIC73kZjTNE8HOv2zBfug6dml9zKnG9Erwvb0QTZSPHYyOL34TeUiTHhK/5akogGrLa7t3nXYlju0GVIAfnCBS06IVlH5K5GEQseh0BnkXDxIWclK7BbLUBeb5Sh0vkQxawAVowpqeddlc6hIh2q41J3QXdacSvSYDgs/xQRtkRIOiLbOGb2dF/K5IWPHaRJ9hY0="
Jan 11 20:18:33 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.65.210
Jan 11 20:18:33 volumio go-librespot[19340]: time="2026-01-11T20:18:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:33 volumio go-librespot[19340]: time="2026-01-11T20:18:33-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:33 volumio go-librespot[19340]: time="2026-01-11T20:18:33-06:00" level=debug msg="completed challenge"
Jan 11 20:18:33 volumio go-librespot[19340]: time="2026-01-11T20:18:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:18:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:33 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.65.210
Jan 11 20:18:33 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.65.210
Jan 11 20:18:33 volumio avahi-daemon[664]: Withdrawing address record for 169.254.65.210 on eth0.
Jan 11 20:18:33 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:33 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:33 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:33 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:33 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:34 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:34 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30.
Jan 11 20:18:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:36 volumio go-librespot[19379]: go-librespot daemon starting...
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=debug msg="app state loaded"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-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 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-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 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=info msg="zeroconf server listening on port 43967"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=debug msg="obtained new client token: AAAiNiCPiQeX83+AkJMM3SPonGl7xQmGDipfQz1YrwCxyqtgVwN3xEX9soKpVWWPUvVLsIekZuSPa9U204DGzA5Cf54UcKzcaLPCK4VSXWWVqAms4i8l502o4I5Xfx9IgAVnyEbnyjUJjHvmr78Hwa8mHLGqhftBQfboOvqIaB/6806hm2p+nMKZZL/HqAWfs2FDvoNBWsJYgUx1piWDEpVCfiIIS+2q/BunLUdKPb5h4amlBS/KsjQp9w=="
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18:36-06:00" level=debug msg="completed challenge"
Jan 11 20:18:36 volumio go-librespot[19380]: time="2026-01-11T20:18: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 20:18:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:37 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:37 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:38 volumio volumio[18174]: info: UPDATER: Scheduling automatic update
Jan 11 20:18:38 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Jan 11 20:18:38 volumio volumio[18174]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Jan 11 20:18:38 volumio volumio[18174]: info: UPDATER: Auto update will take place at: Mon Jan 12 2026 05:51:03 GMT-0600 (Central Standard Time)
Jan 11 20:18:39 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.61.48
Jan 11 20:18:39 volumio avahi-daemon[664]: Registering new address record for 169.254.61.48 on eth0.IPv4.
Jan 11 20:18:39 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:39 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:39 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:39 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:39 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.61.48
Jan 11 20:18:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31.
Jan 11 20:18:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:40 volumio go-librespot[19404]: go-librespot daemon starting...
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="app state loaded"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:40 volumio ntpd[872]: IO: Listen normally on 12904 eth0 169.254.61.48:123
Jan 11 20:18:40 volumio ntpd[872]: IO: new interface(s) found: waking up resolver
Jan 11 20:18:40 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.61.48
Jan 11 20:18:40 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.61.48
Jan 11 20:18:40 volumio avahi-daemon[664]: Withdrawing address record for 169.254.61.48 on eth0.
Jan 11 20:18:40 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:40 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:40 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:40 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:40 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=info msg="zeroconf server listening on port 46495"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="obtained new client token: AACRz2ic/4/HqpeOEPIhvXRF/dcvcaj2orw3mygHTxIQaeWc+BjMkEyJBK5+kkfnyvqf3Uy0YSaLQsLeN1WYM7J2KQlWFBeHLLTCfN0aGK1gc800oL/rZNtpyBOUq/p9n8gYRzqafz51BA4T1O2GBOt0lgKHmB+M12uLkx18lbATCfQKfN9X3QA2QwHLT3OzzaQoXvrfbiZ7euzOiUY4TxmtztT9hUUrbEl12MOjmYdOwvcbkmu8dIT5ag=="
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18:40-06:00" level=debug msg="completed challenge"
Jan 11 20:18:40 volumio go-librespot[19405]: time="2026-01-11T20:18: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 20:18:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:40 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:40 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:42 volumio ntpd[872]: IO: Deleting interface #12904 eth0, 169.254.61.48#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs
Jan 11 20:18:43 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:43 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32.
Jan 11 20:18:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:44 volumio go-librespot[19442]: go-librespot daemon starting...
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="app state loaded"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-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 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-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 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=info msg="zeroconf server listening on port 46099"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="obtained new client token: AABsfFcwY8qNhd7hRFp98Uu6CRmce8Br3qNAAgxW1Fwn0CXa0PtQe0oieeH1o6rhLbxVYEsW/nUcBHuxz+py4MUzZjVBMpviZTabnxUYvKN5KzNOY3aSZQf0edtMaKDEdKVGobPATPlYe/2pB1wN2E82XQQ0EtNTfvgZzq3YyA7wx9x0hz8T8o2GX97tBzSacwIfSpEJ9uu2D2zK5m+s0E9LNfaSqrdU0IOaFoohDhldM5MwqtSppGryow=="
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18:44-06:00" level=debug msg="completed challenge"
Jan 11 20:18:44 volumio go-librespot[19443]: time="2026-01-11T20:18: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 20:18:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:45 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.158.214
Jan 11 20:18:45 volumio avahi-daemon[664]: Registering new address record for 169.254.158.214 on eth0.IPv4.
Jan 11 20:18:45 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:45 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:45 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:45 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:45 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.158.214
Jan 11 20:18:46 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.158.214
Jan 11 20:18:46 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.158.214
Jan 11 20:18:46 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:46 volumio avahi-daemon[664]: Withdrawing address record for 169.254.158.214 on eth0.
Jan 11 20:18:46 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:46 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:46 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:46 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:46 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:46 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Jan 11 20:18:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:47 volumio go-librespot[19482]: go-librespot daemon starting...
Jan 11 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18:47-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18:47-06:00" level=debug msg="app state loaded"
Jan 11 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18:47-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18:47-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18:47-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 20:18:47 volumio go-librespot[19483]: time="2026-01-11T20:18:47-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 20:18:48 volumio go-librespot[19483]: time="2026-01-11T20:18:48-06:00" level=info msg="zeroconf server listening on port 46219"
Jan 11 20:18:48 volumio go-librespot[19483]: time="2026-01-11T20:18:48-06:00" level=debug msg="obtained new client token: AADniP/Uqz5qQ0WuNNu0XYBorXBpIvDvXMfMI4ecO/+ObMvkuZ15u9DVrk64JZffumW5Ev6KKpus9Ts1hd2/QxyMAAcOBHNirndQWYNIqUBByDGKktGpstV9UO5RcseLKaokA2uM2peLD0YOrHKj3wMkLU4EJuNZyD2LjUDWuDrbz3zlg4sEycsEAbro126yKUdzDLS5YKf5kF9ledvTGIqeLMM9gajUMJ6XQMUtgByf7YVY8GXgMLs="
Jan 11 20:18:48 volumio go-librespot[19483]: time="2026-01-11T20:18:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:48 volumio go-librespot[19483]: time="2026-01-11T20:18:48-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:48 volumio go-librespot[19483]: time="2026-01-11T20:18:48-06:00" level=debug msg="completed challenge"
Jan 11 20:18:48 volumio go-librespot[19483]: time="2026-01-11T20:18:48-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 20:18:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:49 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:49 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34.
Jan 11 20:18:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:51 volumio go-librespot[19507]: go-librespot daemon starting...
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=debug msg="app state loaded"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-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 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-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 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=info msg="zeroconf server listening on port 40581"
Jan 11 20:18:51 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.93.67
Jan 11 20:18:51 volumio avahi-daemon[664]: Registering new address record for 169.254.93.67 on eth0.IPv4.
Jan 11 20:18:51 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=debug msg="obtained new client token: AADqTQUI8KLUSqjhTrQ7Xwiu1AjJKdoqsGdk2lEOqIu5pvyVNqMqQhdzI7RcRLnuah9+eHrYQ4uZJPjM184GBPIPhnv3aZttMmXRTohZ2ZXs7uebINbWxDdRNldOiMfCcLCpnkv5vArcgqgAKlFT0IF0c7SmdPq4fFebPdzzMDZTS/DzyQ547X3ThCg8ffubZGJxUGCyVnmmR3lsEmzJJJBhpxd4SSKJ+QtbKSYS3DUQiGV1l5cFm+u+Dw=="
Jan 11 20:18:51 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:51 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:51 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:51 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.93.67
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18:51-06:00" level=debug msg="completed challenge"
Jan 11 20:18:51 volumio go-librespot[19508]: time="2026-01-11T20:18: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 20:18:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:52 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.93.67
Jan 11 20:18:52 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.93.67
Jan 11 20:18:52 volumio avahi-daemon[664]: Withdrawing address record for 169.254.93.67 on eth0.
Jan 11 20:18:52 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:52 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:52 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:52 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:52 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:52 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:52 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35.
Jan 11 20:18:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:55 volumio go-librespot[19547]: go-librespot daemon starting...
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=debug msg="app state loaded"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-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 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-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 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=info msg="zeroconf server listening on port 38615"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=debug msg="obtained new client token: AABtf6vgqAyIZfunEmfMBnslKKp3afJfxy70RPH3gS+qVZc4qrltfZpr208SEFN21/4RvWOs8B91CgZKZngkdozipYdyI/vn94i2k+sP4K3TpCpr+uor9dh2OpwiXP68tvANZeWb4jt+I1cTPcsUjVvkg76iveqQTerBWA3UbEm/vr6yBRYGXY87FXhy+mlHt0rokKcQh6ZNxLEA0StgFZdmFpfjdOTa+vcNcCADRnx3NvudHNY22SMDHg=="
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18:55-06:00" level=debug msg="completed challenge"
Jan 11 20:18:55 volumio go-librespot[19548]: time="2026-01-11T20:18: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 20:18:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:18:55 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:55 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:57 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.82.65
Jan 11 20:18:57 volumio avahi-daemon[664]: Registering new address record for 169.254.82.65 on eth0.IPv4.
Jan 11 20:18:57 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:18:57 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:57 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:57 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:57 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.82.65
Jan 11 20:18:58 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.82.65
Jan 11 20:18:58 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.82.65
Jan 11 20:18:58 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:18:58 volumio avahi-daemon[664]: Withdrawing address record for 169.254.82.65 on eth0.
Jan 11 20:18:58 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:18:58 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:58 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:18:58 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:18:58 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:18:58 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:18:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36.
Jan 11 20:18:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:18:59 volumio go-librespot[19588]: go-librespot daemon starting...
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=debug msg="app state loaded"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=debug msg="stored credentials not found"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-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 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-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 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-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 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=info msg="zeroconf server listening on port 43631"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=debug msg="obtained new client token: AAC04gRdAUSMe8pmGXkq+4K/Hux+ku4RulLwS7A5RXIANw+TlsuG4qacNrCJYWdTTodJr+Ci1UZhhkEDMcgOqD+C7id1oAgyNnzPXjFIy0QAB7qoFNYWQ+zoFByFN9OR3rBg6f+8qUZijp+JC997gUb2NSx9sFbLRru22y2RkGXk/A5qtJqZExKr8KfCxOFoF1k4G+XY4S1ppQ2c9Nyky+N7RQFpPdJn4oz9dTP+nfUgOoI7S72VbvIVxw=="
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=debug msg="completed keyexchange"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-06:00" level=debug msg="completed challenge"
Jan 11 20:18:59 volumio go-librespot[19589]: time="2026-01-11T20:18:59-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 20:18:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:18:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:01 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:01 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.84.174
Jan 11 20:19:02 volumio avahi-daemon[664]: Registering new address record for 169.254.84.174 on eth0.IPv4.
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:19:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37.
Jan 11 20:19:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.84.174
Jan 11 20:19:02 volumio go-librespot[19626]: go-librespot daemon starting...
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.84.174
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.84.174
Jan 11 20:19:02 volumio avahi-daemon[664]: Withdrawing address record for 169.254.84.174 on eth0.
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:19:02 volumio go-librespot[19627]: time="2026-01-11T20:19:02-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:02 volumio go-librespot[19627]: time="2026-01-11T20:19:02-06:00" level=debug msg="app state loaded"
Jan 11 20:19:02 volumio go-librespot[19627]: time="2026-01-11T20:19:02-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:02 volumio go-librespot[19627]: time="2026-01-11T20:19:02-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:02 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:19:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19: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-gew4.spotify.com:80]"
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-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 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-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 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-06:00" level=info msg="zeroconf server listening on port 43217"
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-06:00" level=debug msg="obtained new client token: AACsahnnlWj63hY6+YqE10ZdpEwUxqBvq0zYueLDiniCLFKPPQ+0bDeGG3TYo1KRrqmwHkX1YhOlp/3uKsRpcqbJbixRuUlSKrM4IPorp/XDen6TbBj8h+eYCpSLVhw5WcgAP/fKjVf5BDuix5B4rN7rn8t0LAHR1+zf3LoWR5+8oXV1bGzI9PSP9vj2fSDXgObqUSGt8A9ykNpkO0BuuOnoifxEQ3S5xHSOPw2028TE2uyWUnhAHQQ="
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-06:00" level=debug msg="completed challenge"
Jan 11 20:19:03 volumio go-librespot[19627]: time="2026-01-11T20:19:03-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:19:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:04 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:04 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38.
Jan 11 20:19:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:06 volumio go-librespot[19650]: go-librespot daemon starting...
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=debug msg="app state loaded"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-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 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-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 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=info msg="zeroconf server listening on port 34757"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=debug msg="obtained new client token: AAAlB9qVkONUcObrXux6R50k5s6iTPY0pRXtool0PbxkbgGeDzhNbMQslDPUVPEBKApxwMMPbk90sxFRwmee2bOjBP0eaNR5hvcKfPbqJIvjWF3dy6tC9B0RasRFEl698loYuWc3KB2YonA7cCn9N+ZQFKXiPXFETUKc4KWbjyowPk5ehyejrYC0wJUrfsjOsQQJOXazjzjIaksIT61Ik4g0x1IVo6jcfEgz7kxYMJMEvMtAyJjVntpOEA=="
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19:06-06:00" level=debug msg="completed challenge"
Jan 11 20:19:06 volumio go-librespot[19651]: time="2026-01-11T20:19: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 20:19:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:07 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:07 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:08 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.64.89
Jan 11 20:19:08 volumio avahi-daemon[664]: Registering new address record for 169.254.64.89 on eth0.IPv4.
Jan 11 20:19:08 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:19:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:08 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.64.89
Jan 11 20:19:09 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.64.89
Jan 11 20:19:09 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.64.89
Jan 11 20:19:09 volumio avahi-daemon[664]: Withdrawing address record for 169.254.64.89 on eth0.
Jan 11 20:19:09 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:19:09 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:19:09 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:09 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:09 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39.
Jan 11 20:19:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:10 volumio go-librespot[19693]: go-librespot daemon starting...
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="app state loaded"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=info msg="zeroconf server listening on port 38691"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="obtained new client token: AABveRvH3gjTYZc/oOJESskh5g3QsHrdRLCS36cJVs9aLKsiH73/lAyj70VRKgykqWJLvNmJsBA/L3MHbDQbhNfZARG1EcKV5MSSra8FrksHr0gVZ1GRyr643j1Dhobljg+GIkosoqTJ64X01AtwU41Ctx7qomgIwPllePZBvfN4P9vCr9ZnaFNDTKWpwip8yQuvvuKMuTuMPqGkGPU6+RFLW99X6x+TAjPv2JU7LYf/q3wzaIxfPy4BJA=="
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19:10-06:00" level=debug msg="completed challenge"
Jan 11 20:19:10 volumio go-librespot[19694]: time="2026-01-11T20:19: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 20:19:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:10 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:10 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40.
Jan 11 20:19:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:13 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:13 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:13 volumio go-librespot[19716]: go-librespot daemon starting...
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="app state loaded"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:14 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.1.233
Jan 11 20:19:14 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:19:14 volumio avahi-daemon[664]: Registering new address record for 169.254.1.233 on eth0.IPv4.
Jan 11 20:19:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-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 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-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 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=info msg="zeroconf server listening on port 36769"
Jan 11 20:19:14 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.1.233
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="obtained new client token: AAAw7aUsHeI7xa4WuVZbqEFc6oqCOkGZfXupH24Sbl/NKLUugOw8r4fW6mQQ47xFmuF3PJRIjlzoUtP8u5wYppMs+ePgzeBAbIrybcPGsL1ffCXQXstYOIzpW5dLHR0Vx089PaQq0JdwA5KHmLKUmbK7fdXIq0C5iLF+R3rpZiAcoqQ2tnvXJUB70e78SEfPMkPuGRgPzOSbqvULc9m/05d7Vaur1SYQtKAdKRu8sKDN3yFQ19q6vjUnxA=="
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=debug msg="completed challenge"
Jan 11 20:19:14 volumio go-librespot[19717]: time="2026-01-11T20:19:14-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:19:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:15 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.1.233
Jan 11 20:19:15 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.1.233
Jan 11 20:19:15 volumio avahi-daemon[664]: Withdrawing address record for 169.254.1.233 on eth0.
Jan 11 20:19:15 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:19:15 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:15 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:15 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:15 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:19:16 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:16 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41.
Jan 11 20:19:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:17 volumio go-librespot[19758]: go-librespot daemon starting...
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=debug msg="app state loaded"
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Jan 11 20:19:17 volumio go-librespot[19759]: time="2026-01-11T20:19:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Jan 11 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-06:00" level=info msg="zeroconf server listening on port 36343"
Jan 11 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-06:00" level=debug msg="obtained new client token: AAD/vmUb1a4VRY4GtyBfKQlyDSxF/aWBUKtoBB6q2/V8WP6Leo8yWVi/O1aVFY5CJO4eq4HJbc5IqFRxzO+vlFkVvS1Dl/8wrEROCZsXXTl0OsKYq9GM6aRAi8W/xuA4tN0YX+DuoV0pb9+CKkNyZvdrHBrBTEgrHcL+Ime02wUrmmLhFCiUB4TloGQ1SA4jRwrsBCwNBmalZNRQ/G9xITaFKChaHFMIC9+TYh0NZnhrOrktgCf+wuo="
Jan 11 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-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 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Jan 11 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-06:00" level=debug msg="completed challenge"
Jan 11 20:19:18 volumio go-librespot[19759]: time="2026-01-11T20:19:18-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Jan 11 20:19:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.82.111
Jan 11 20:19:19 volumio avahi-daemon[664]: Registering new address record for 169.254.82.111 on eth0.IPv4.
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.82.111
Jan 11 20:19:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.82.111
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.82.111
Jan 11 20:19:19 volumio avahi-daemon[664]: Withdrawing address record for 169.254.82.111 on eth0.
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:19:19 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:19:19 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:19 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:19 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:19 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:19 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42.
Jan 11 20:19:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:21 volumio go-librespot[19812]: go-librespot daemon starting...
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="app state loaded"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-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 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-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 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=info msg="zeroconf server listening on port 44729"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="obtained new client token: AAChTtlZ5h9pdcbUmXdOvf5AijSjwtcz1WHtvr62NFOVZNzp8OzJaakiZXFMf1IN7mcrA+m52M8YfEjtRw44naQDylzmlBVQ3d37WQAKWffhLaLAfO7iszDYn7eSrdv+zyl/pMLGw6crrgS6EBtqGY1M+BIy/nkmr004RwCHUeEWzG9GixBR0GgUVVljLP58J2WEKgMv/7LlhgDH3u0qrPK+KGhdPFDOnSzcLCdRaA9lBXAWYT9Pz/ew5w=="
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19:21-06:00" level=debug msg="completed challenge"
Jan 11 20:19:21 volumio go-librespot[19813]: time="2026-01-11T20:19: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 20:19:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:22 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:22 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:24 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.136.74
Jan 11 20:19:24 volumio avahi-daemon[664]: Registering new address record for 169.254.136.74 on eth0.IPv4.
Jan 11 20:19:24 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:19:24 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.136.74
Jan 11 20:19:24 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:24 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:24 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:25 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.136.74
Jan 11 20:19:25 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.136.74
Jan 11 20:19:25 volumio avahi-daemon[664]: Withdrawing address record for 169.254.136.74 on eth0.
Jan 11 20:19:25 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:19:25 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:19:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43.
Jan 11 20:19:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:25 volumio go-librespot[19852]: go-librespot daemon starting...
Jan 11 20:19:25 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:25 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:25 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="app state loaded"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=info msg="zeroconf server listening on port 35251"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="obtained new client token: AADTUeOdUJLMhoG1dsYkeevob6YycaouLggqOY2Z/gTCxSpn5Vs5pfUVBIIMjrtuuS2573o0QIlBKRlATOc0GqvK13ebq86ZhMzF2Oe8bVcS+mq7SsCp6DS2brplFETBq9/AEVDpE0LrHHlXfjUlxaN3Ps4bRyexCdiYcS4wAunCKrvzBBaCMIkbRvsRCSOHYtQorm/614RVrreJTFD6sgGZzU/OPRk5eOOI6I6o71cI4tY1JQ0kzfG5Jw=="
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19:25-06:00" level=debug msg="completed challenge"
Jan 11 20:19:25 volumio go-librespot[19853]: time="2026-01-11T20:19: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 20:19:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:25 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:25 volumio volumio[18174]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44.
Jan 11 20:19:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:28 volumio go-librespot[19864]: go-librespot daemon starting...
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19:28-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19:28-06:00" level=debug msg="app state loaded"
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19:28-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19:28-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19: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 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19: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 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19: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 20:19:28 volumio volumio[18174]: info: Initializing connection to go-librespot Websocket
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19:28-06:00" level=info msg="zeroconf server listening on port 37573"
Jan 11 20:19:28 volumio go-librespot[19865]: time="2026-01-11T20:19:28-06:00" level=debug msg="new websocket client"
Jan 11 20:19:29 volumio volumio[18174]: info: Connection to go-librespot Websocket established
Jan 11 20:19:29 volumio go-librespot[19865]: time="2026-01-11T20:19:29-06:00" level=debug msg="obtained new client token: AACm/3wXY98Fl0f5aGRTs/3jfMvR8bG9CyIGPBcaqh4NNLmXf7PAxpbtnPC3fshJUVTuxniB4GDf2NW2DtHH64ylh7jO+fexBpjVpS1cHjD8EHZv/YugdsKmrB78SPl89GN7N4XWLqabbZhYZXqWxdNlmhIDj0WsH4q6W8Kx8WXjcb4imj6/naepRMng9890N62+L7yMhgWC7EjYnUi9maRODoRfuDjgd3KllLY5aJJcVoOxqJeVX/k="
Jan 11 20:19:29 volumio go-librespot[19865]: time="2026-01-11T20:19:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Jan 11 20:19:29 volumio go-librespot[19865]: time="2026-01-11T20:19:29-06:00" level=debug msg="completed keyexchange"
Jan 11 20:19:29 volumio go-librespot[19865]: time="2026-01-11T20:19:29-06:00" level=debug msg="completed challenge"
Jan 11 20:19:29 volumio go-librespot[19865]: time="2026-01-11T20:19: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 20:19:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Jan 11 20:19:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Jan 11 20:19:29 volumio volumio[18174]: info: Connection to go-librespot Websocket closed
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: using IPv4LL address 169.254.217.122
Jan 11 20:19:30 volumio avahi-daemon[664]: Registering new address record for 169.254.217.122 on eth0.IPv4.
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: adding route to 169.254.0.0/16
Jan 11 20:19:30 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:30 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:30 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.217.122
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: 80:69:1a:20:db:59(80:69:1a:20:db:59) claims 169.254.217.122
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: 10 second defence failed for 169.254.217.122
Jan 11 20:19:30 volumio avahi-daemon[664]: Withdrawing address record for 169.254.217.122 on eth0.
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: deleting route to 169.254.0.0/16
Jan 11 20:19:30 volumio dhcpcd[684]: eth0: probing for an IPv4LL address
Jan 11 20:19:30 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:30 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Jan 11 20:19:30 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Jan 11 20:19:32 volumio volumio[18174]: info: Getting Spotify volume
Jan 11 20:19:32 volumio volumio[18174]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 11 20:19:32 volumio volumio[18174]: Error: connect ECONNREFUSED 127.0.0.1:9879
Jan 11 20:19:32 volumio volumio[18174]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Jan 11 20:19:32 volumio volumio[18174]: errno: -111,
Jan 11 20:19:32 volumio volumio[18174]: code: 'ECONNREFUSED',
Jan 11 20:19:32 volumio volumio[18174]: syscall: 'connect',
Jan 11 20:19:32 volumio volumio[18174]: address: '127.0.0.1',
Jan 11 20:19:32 volumio volumio[18174]: port: 9879,
Jan 11 20:19:32 volumio volumio[18174]: response: undefined
Jan 11 20:19:32 volumio volumio[18174]: }
Jan 11 20:19:32 volumio volumio[18174]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 11 20:19:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45.
Jan 11 20:19:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Jan 11 20:19:32 volumio go-librespot[19930]: go-librespot daemon starting...
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=info msg="running go-librespot 0.4.0"
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=debug msg="app state loaded"
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=debug msg="stored credentials not found"
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-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 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-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 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=info msg="zeroconf server listening on port 41063"
Jan 11 20:19:32 volumio sudo[19940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 20:18'
Jan 11 20:19:32 volumio sudo[19940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=debug msg="obtained new client token: AACCAyIiI1TmGYM6s8l8K+P6AdgGDMIkuxJ05WunDx9ydRWrxrb8szfQ7nSv/M7LZYbjjAIFvZcns+0RRK7T1wtQRbseD6TnTSnnCs/Vjhp+jQSsfmeogOjel8fYTwhsnLidRWuOd6s3eF5N4BNpSZGvi6e5YOHJBTTrXS+9zNs4iKCZ9TfxJqezdMrp8ZD74et67HaDkb9POyTxnTZ5zgMoIeT52ircFivpwtbxt66JZGDwo2+BmC8VWQ=="
Jan 11 20:19:32 volumio go-librespot[19931]: time="2026-01-11T20:19:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
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"