Feb 23 12:56:01 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:01 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97. Feb 23 12:56:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:01 volumio go-librespot[21780]: go-librespot daemon starting... Feb 23 12:56:01 volumio go-librespot[21781]: time="2026-02-23T12:56:01-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:01 volumio go-librespot[21781]: time="2026-02-23T12:56:01-06:00" level=debug msg="app state loaded" Feb 23 12:56:01 volumio go-librespot[21781]: time="2026-02-23T12:56:01-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:01 volumio go-librespot[21781]: time="2026-02-23T12:56:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-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]" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-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]" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=info msg="zeroconf server listening on port 46335" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=debug msg="obtained new client token: AACBvLbgt4/XndJadpXOPnx5KkfVLYoHWJDH3VGcLkWl3hfKT2hQLJsO/UXhVmqyYoz/rtFdOJA8osUIIrY1N1qKf5KCpF3FTGSanv95JLpiV8JcQpfoHoDf7+nrMbkJBBo1+S53HC/yZp206ZzGthmEr0e9tBM/JI3pVJ2qSS0Nv9dPB+jwIDCOra6iCvZnDLH+M1dglm9AIQJWR/dVLCEvjsv67DuJdj7K/cqGBzJnGxnTg6vJAscfVA==" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=debug msg="completed challenge" Feb 23 12:56:02 volumio go-librespot[21781]: time="2026-02-23T12:56:02-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:04 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:04 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 12:56:04 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 12:56:05 volumio volumio[21205]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 23 12:56:05 volumio volumio[21205]: info: Completed starting MyVolumio Plugin Feb 23 12:56:05 volumio volumio[21205]: [Metrics] CommandRouter: 55s 43.94ms Feb 23 12:56:05 volumio volumio[21205]: info: CoreCommandRouter::volumiosetStartupVolume Feb 23 12:56:05 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 12:56:05 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 12:56:05 volumio volumio[21205]: info: CoreCommandRouter::Close All Modals sent Feb 23 12:56:05 volumio volumio[21205]: info: CoreCommandRouter::Close All Modals sent Feb 23 12:56:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Feb 23 12:56:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:05 volumio go-librespot[21805]: go-librespot daemon starting... Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-06:00" level=debug msg="app state loaded" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-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]" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-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]" Feb 23 12:56:05 volumio go-librespot[21806]: time="2026-02-23T12:56:05-06:00" level=info msg="zeroconf server listening on port 37041" Feb 23 12:56:06 volumio go-librespot[21806]: time="2026-02-23T12:56:06-06:00" level=debug msg="obtained new client token: AABFRxPbcCtx6AHOWtEdB9aGuQzQsFMHbx+EmYVB+A4zivBweYiKAJ2A8O5hRANgEeFp4PE/7JgmlKYY8yRsywdtjUjfwJlnlNlisyPXqvfoJ0PstN9FvQPD7n1F4SfN5xLBrXeTHES/k3sD0cdsEnIrzrT3XGmts91ILUFXyfV9ymMGIwHlXN8nAaUkV8Q/KqUXaDopRt1fCZz2cwMUExO8mlcvmg6FzrpT6vT2LryHypK0QVY/WeU=" Feb 23 12:56:06 volumio go-librespot[21806]: time="2026-02-23T12:56:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:06 volumio go-librespot[21806]: time="2026-02-23T12:56:06-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:06 volumio go-librespot[21806]: time="2026-02-23T12:56:06-06:00" level=debug msg="completed challenge" Feb 23 12:56:06 volumio go-librespot[21806]: time="2026-02-23T12:56: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 " Feb 23 12:56:06 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 12:56:06 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 12:56:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:06 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 12:56:07 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:07 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99. Feb 23 12:56:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:09 volumio go-librespot[21816]: go-librespot daemon starting... Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="app state loaded" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=info msg="zeroconf server listening on port 34793" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="obtained new client token: AADMJBLeufCyalFmJNp9rSgvh6ctthNKXndNHZh8MfC+4lqECFBCDOrkQD9U56gWwKXVLLtXi4C8xBu8PdkS6N31tCqhmqxGZ2iwd48PCb112Rt8rp5otlN9Cwv9aUHtM2/F5fOvYIWCsJda1CvZuVH40OoYJZo8RBNaIHWFoh+IUMU10zgII2eTsriF4yZB1djM2Gu6i5bm2stPedbAWFiKQs+8Imyoab8Wbg+PwKuJOAbPMrFlOgNLEw==" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=debug msg="completed challenge" Feb 23 12:56:09 volumio go-librespot[21817]: time="2026-02-23T12:56:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:10 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:10 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:12 volumio volumio[21205]: info: BOOT COMPLETED Feb 23 12:56:13 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:13 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100. Feb 23 12:56:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:13 volumio go-librespot[21839]: go-librespot daemon starting... Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="app state loaded" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=info msg="zeroconf server listening on port 38365" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="obtained new client token: AABQppHiDYPJdBMatEDJejXwsFHTTKc9RPNj3aHMrV18ugg12p81gM0VXTHaU3gfrDXeY/Xpt12Kf9EZPRgAW2r2/E34U3F06fo2EHX1GJUX7TQ2YRmmYuUHxOG9orpqsKbmRcZRQI20scJ/fUpUEuh/V5sq/Nb2lkd5n+4Jje6gqlavSfN9Em4UPaVXK1+C4m9xkdNJoulklZBehiswzpYkJljffAUnbZSGTARDk1OWKjGhucWuvcQwEg==" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=debug msg="completed challenge" Feb 23 12:56:13 volumio go-librespot[21840]: time="2026-02-23T12:56:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:16 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:16 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Feb 23 12:56:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:16 volumio go-librespot[21851]: go-librespot daemon starting... Feb 23 12:56:16 volumio go-librespot[21852]: time="2026-02-23T12:56:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:16 volumio go-librespot[21852]: time="2026-02-23T12:56:16-06:00" level=debug msg="app state loaded" Feb 23 12:56:16 volumio go-librespot[21852]: time="2026-02-23T12:56:16-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:16 volumio go-librespot[21852]: time="2026-02-23T12:56:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=info msg="zeroconf server listening on port 46093" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="obtained new client token: AAAkPP+K/MyWUk1wyEuALvbXU7gTchGy8rBM/15VbMWEe137ZvzVq6D20YLy3kVqedvo07Q5gese5kPtN+NlQ0ruUxKj7TLGsZJ6yE5GUPVWaskD3hilRqghQ8lxsnhPiEcZaElLWkgWYjq9lzYIzDxwS/YQfKARGyj/ebf8u/cwOB5WoxsgQevy2l3GuN0VT8PNDbWTHnQe+dkQeW52jPmo/xErbLwJc+gQsmfHR/zkLkOwdDPfSZk=" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=debug msg="completed challenge" Feb 23 12:56:17 volumio go-librespot[21852]: time="2026-02-23T12:56:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:19 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:19 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Feb 23 12:56:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:20 volumio go-librespot[21859]: go-librespot daemon starting... Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-06:00" level=debug msg="app state loaded" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-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]" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-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]" Feb 23 12:56:20 volumio go-librespot[21860]: time="2026-02-23T12:56:20-06:00" level=info msg="zeroconf server listening on port 33221" Feb 23 12:56:21 volumio go-librespot[21860]: time="2026-02-23T12:56:21-06:00" level=debug msg="obtained new client token: AACjw9JyxntdypkxAKK6/gQCv9y5yJ6RW270FmQKa931fQIX035oNN5iIsEQb0WXbbeYz6iltTk14iAAiOdzc5fLtYTmBrAD4KXqMGRycp7Ij5JHaNedi9b2u57UVYxj6jcMWKh/vSQO9FpIPrz+Hpbsi6ZEnFybYrhufClB7ZhAx+A5xs46tM0nGurkniXbRlRED/TDanXRqZcS6/PGkS6LuDri0y+K8eSuSX9M5ZbfsSxPLLtl+1Q=" Feb 23 12:56:21 volumio go-librespot[21860]: time="2026-02-23T12:56:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:21 volumio go-librespot[21860]: time="2026-02-23T12:56:21-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:21 volumio go-librespot[21860]: time="2026-02-23T12:56:21-06:00" level=debug msg="completed challenge" Feb 23 12:56:21 volumio go-librespot[21860]: time="2026-02-23T12:56: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 " Feb 23 12:56:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:22 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:22 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Feb 23 12:56:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:24 volumio go-librespot[21881]: go-librespot daemon starting... Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="app state loaded" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-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]" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-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]" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=info msg="zeroconf server listening on port 33145" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="obtained new client token: AABcuu50iWD2LkKrW8zhjzmu+1huVFmNapfwC97sbZMy2ScH9bCciAjd0WFOrxASWqey/aRVdlqjuDw9JcjE+YtqK53P6RCW175BpOCRmkFYELTtjTr1kQ5xRd++2+z/gUdi7KkDY83CvKE4d3BW+snhXQ91qvBP9G9yothVDJse2fVZ0nFeVjtpor7lrAbg7vkXx1Ob88O7o2zviXhZ3PVUVxUqqT5Wwm6ezdMxEBXK27DFbf/9QEYBmA==" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=debug msg="completed challenge" Feb 23 12:56:24 volumio go-librespot[21882]: time="2026-02-23T12:56:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:25 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:25 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:26 volumio volumio[21205]: info: UPDATER: Scheduling automatic update Feb 23 12:56:26 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Feb 23 12:56:26 volumio volumio[21205]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Feb 23 12:56:26 volumio volumio[21205]: info: UPDATER: Auto update will take place at: Tue Feb 24 2026 04:18:06 GMT-0600 (Central Standard Time) Feb 23 12:56:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Feb 23 12:56:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:28 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:28 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:28 volumio go-librespot[21889]: go-librespot daemon starting... Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=debug msg="app state loaded" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56: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]" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56: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]" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56: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]" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=info msg="zeroconf server listening on port 45729" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=debug msg="obtained new client token: AAAafX6Nkx3Q6Mt7BUa+oq6mIKbv8EPvYQxsjSzSO0dM+DeAeeeqQQsMZIsWuLbIyqzfJOQMiWMDlNkqvGOU4Lgg0cNLnBtuTI17BuCE8C9hVhPPUu8SxB8GWPI4yegwXZmYQoErhG+us5pVPjIGkzNb1io+J3XV2La2V6N8kN1MykUr+xoqnK6oogK6vutBmARmTKapv2Th/+0oAFW5K1bHwOt9v/teGqAc8/gVceJV9WewkBtGtEeyUQ==" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=debug msg="completed challenge" Feb 23 12:56:28 volumio go-librespot[21890]: time="2026-02-23T12:56:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:31 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:31 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Feb 23 12:56:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:31 volumio go-librespot[21897]: go-librespot daemon starting... Feb 23 12:56:31 volumio go-librespot[21898]: time="2026-02-23T12:56:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:31 volumio go-librespot[21898]: time="2026-02-23T12:56:31-06:00" level=debug msg="app state loaded" Feb 23 12:56:31 volumio go-librespot[21898]: time="2026-02-23T12:56:31-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:31 volumio go-librespot[21898]: time="2026-02-23T12:56:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56: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]" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56: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]" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56: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]" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56:32-06:00" level=info msg="zeroconf server listening on port 43647" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56:32-06:00" level=debug msg="obtained new client token: AACiKOWXdNt03qJJ43+1refOxBW1XvHkFZFnJEJmckn4H3aEuupCWCizunWKlpjmCzQSizrHtKGPREsWza8W2PcDNmCaMXJRMBig5dJtpZZNIr6pl25d4gfW53JTK21HEKB0RP6ImlgsXbiV/5ABxmDT3Z0FIGWOgx+XmXz8rdzo9iM4rBF8mB8LOrknLz/4W8be6T/5JusTz9nHCp9dY1w/dnufwNF2M+uJNTjPfwIIHFDRqEZfet1AcA==" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56:32-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56:32-06:00" level=debug msg="completed challenge" Feb 23 12:56:32 volumio go-librespot[21898]: time="2026-02-23T12:56:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:34 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:34 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Feb 23 12:56:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:35 volumio go-librespot[21924]: go-librespot daemon starting... Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-06:00" level=debug msg="app state loaded" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-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]" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-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]" Feb 23 12:56:35 volumio go-librespot[21925]: time="2026-02-23T12:56:35-06:00" level=info msg="zeroconf server listening on port 33323" Feb 23 12:56:36 volumio go-librespot[21925]: time="2026-02-23T12:56:36-06:00" level=debug msg="obtained new client token: AAACHeR8APvsI/AduKMhouCnOc/9lqbIacR8GIxSXnr8YPsRd50xE8r/PfGFGOE9fan22dl+FHBnwmYEQbS7v734MCTWLir/rFvMV0pEQ15gynIRMCGgnTIYiMj3e5h42jLR3tPjmOoq64plzZ2KSe28rgpvVQJxqO/VUPh3S4b706H1LD5l0HqjXbUxfsqj68XVHLqKGv+NC1c7YDGMiCn7bvdHCA97bvn+eM74mFrNCUnIuuAOuyk=" Feb 23 12:56:36 volumio go-librespot[21925]: time="2026-02-23T12:56:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:36 volumio go-librespot[21925]: time="2026-02-23T12:56:36-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:36 volumio go-librespot[21925]: time="2026-02-23T12:56:36-06:00" level=debug msg="completed challenge" Feb 23 12:56:36 volumio go-librespot[21925]: time="2026-02-23T12:56: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 " Feb 23 12:56:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:37 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:37 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Feb 23 12:56:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:39 volumio go-librespot[21932]: go-librespot daemon starting... Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="app state loaded" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=info msg="zeroconf server listening on port 39097" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="obtained new client token: AADGn4Bi0XyK5lo55rUNaKtRkjmoeLoj3GXUY3+Oqv/okGCoApj/kY/tdzRDJWv82Y6CoeTpi1toJRQrhoRXMXbyo25Db+Mc1jSp0UVcE3boMch6cP+7hroOiEYdPWJrdeiozaGc+3m7KKxxu9R75hwF1zN746Rk/E/4vNOa5Nu+hz9wrz91mKXmXNnaQcrPBbTcMWYNG5mTbKCJ8thchXw8qd+zFFOnMOB0aCoa8X9FLE9JC2MHoL07Nw==" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=debug msg="completed challenge" Feb 23 12:56:39 volumio go-librespot[21933]: time="2026-02-23T12:56:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:40 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:40 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Feb 23 12:56:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:43 volumio go-librespot[21954]: go-librespot daemon starting... Feb 23 12:56:43 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:43 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="app state loaded" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-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]" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-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]" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=info msg="zeroconf server listening on port 38053" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="obtained new client token: AAAQHmrSrrJUkuM+mmKUBZz7RKP7M4bfJzvi21pdrgCUEt9fMPjkOLiJn0nwCs+6Czm5MCHk4NMTS3aKqbwDkF8DV41FI9nMj3TXz/Ph5t4dpxDSjBEGCrB+yFh/3jGjZVQKrYp+8IVYJS7ZvX1UBMUMTtNRHCvy+3CGxom1/9ndwaaFXSS3Pviq4DFe7B03jSUG2uKdDhIpOQtjbaQgCm80nXdYXiDYFsZWYtZdvFAYlgH7Yqv5FriHNg==" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=debug msg="completed challenge" Feb 23 12:56:43 volumio go-librespot[21955]: time="2026-02-23T12:56:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:46 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:46 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Feb 23 12:56:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:46 volumio go-librespot[21962]: go-librespot daemon starting... Feb 23 12:56:46 volumio go-librespot[21963]: time="2026-02-23T12:56:46-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:46 volumio go-librespot[21963]: time="2026-02-23T12:56:46-06:00" level=debug msg="app state loaded" Feb 23 12:56:46 volumio go-librespot[21963]: time="2026-02-23T12:56:46-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:46 volumio go-librespot[21963]: time="2026-02-23T12:56:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56: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]" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56: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]" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56: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]" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56:47-06:00" level=info msg="zeroconf server listening on port 38951" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56:47-06:00" level=debug msg="obtained new client token: AACPIc4/F+gzErFVCExWRfd/7DT5bwvaNVGVORdi273co2SmhRSLdPgVIJWJtGs00afxccDtKiR1j24N3H4MzFzxp7lBjicfB2j8dA6xRpOzUUHdkobGnoCLtZRE8VC6LDXYApRkkUxz1X3zcQXsmhSNygPzL0ptabs6LTqppMOcY0lCLjeh8As9Rqtz3qw+4sHIB49XgNe6/9fBDg2uFqvFSmD41rcuZ2OgyAiT82DSIlhvswN5Pdf23w==" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56:47-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56:47-06:00" level=debug msg="completed challenge" Feb 23 12:56:47 volumio go-librespot[21963]: time="2026-02-23T12:56:47-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:49 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:49 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Feb 23 12:56:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:50 volumio go-librespot[21970]: go-librespot daemon starting... Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-06:00" level=debug msg="app state loaded" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-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]" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-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]" Feb 23 12:56:50 volumio go-librespot[21971]: time="2026-02-23T12:56:50-06:00" level=info msg="zeroconf server listening on port 36595" Feb 23 12:56:51 volumio go-librespot[21971]: time="2026-02-23T12:56:51-06:00" level=debug msg="obtained new client token: AABRTiR4V8o052sX8U0EJUjxHNUHgp0iRjUMHMCf1m2CaLleymtGo8EJ/1qx5E2nnZ+HW501IuQb9Z01fC2V0Phg8CPi0bmQzTU1m8IhkpRBgXcjsAnHCXkYAdJivUQS5YtTRdTwIj9nRmzH+ghacxpV3qAngFXY2jf+liptFU+GQgVriK5PvyFdbMJRaxV7DpzFbEi7ImDPbOdeu6O5unJtp7aImoz4UC39l2rlFva17b9A+yzV5xo=" Feb 23 12:56:51 volumio go-librespot[21971]: time="2026-02-23T12:56:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:51 volumio go-librespot[21971]: time="2026-02-23T12:56:51-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:51 volumio go-librespot[21971]: time="2026-02-23T12:56:51-06:00" level=debug msg="completed challenge" Feb 23 12:56:51 volumio go-librespot[21971]: time="2026-02-23T12:56: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 " Feb 23 12:56:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:52 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:52 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Feb 23 12:56:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:54 volumio go-librespot[21992]: go-librespot daemon starting... Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="app state loaded" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=info msg="zeroconf server listening on port 33753" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="obtained new client token: AADxEQUB8L1GWFiIMuALfPpCzWNPfp1F0VbubcNGg6pQP/+hGqqeMkrSX1brG/h7v6GXG3o17WyH8pTcyzjKIEyojRWuGXQOekQ/vD/EbuN1+tIimQASAwWUOV1Nzb8SbMZzwUNbYF5I17ZGziYR0dwdKtDpn/r0vW8jJKN9CbEW99Q94fEs3z28APRzqVvFBemOP25O+W2OzldkfhyNsEx2N9u0mJKKFvQ9TxO1AegFDUBRJqWJ91Bccg==" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-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" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=debug msg="completed challenge" Feb 23 12:56:54 volumio go-librespot[21993]: time="2026-02-23T12:56:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:55 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:55 volumio volumio[21205]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:56:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Feb 23 12:56:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:56:58 volumio go-librespot[22005]: go-librespot daemon starting... Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="app state loaded" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="stored credentials not found" Feb 23 12:56:58 volumio volumio[21205]: info: Initializing connection to go-librespot Websocket Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="new websocket client" Feb 23 12:56:58 volumio volumio[21205]: info: Connection to go-librespot Websocket established Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-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]" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-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]" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=info msg="zeroconf server listening on port 40875" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="obtained new client token: AABS2cKkWhWvgJot76jyGGQ9LVQokwvXN25CJBg7KNCmR5xX2aKxhr/AovEDf66Q70/A91z+2g6msS8xOUplVc7LZi0fnwL2YUFJHDecSV4G+wPHQXuCgW0yaRIgaQk7qlKxdumaWgOydp337NficfGOQEtwqCL5dQwXJ0k+Lsf4lEfpaIWPxplz2hS+fPJVLxTcU/8PL+e/LZu9zl18hPvu9Vl9riRUUhkNECWRskIRuPkxIKEqkRGQsw==" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="completed keyexchange" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=debug msg="completed challenge" Feb 23 12:56:58 volumio go-librespot[22006]: time="2026-02-23T12:56:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 12:56:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 12:56:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 12:56:58 volumio volumio[21205]: info: Connection to go-librespot Websocket closed Feb 23 12:57:01 volumio volumio[21205]: info: Getting Spotify volume Feb 23 12:57:01 volumio volumio[21205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 12:57:01 volumio volumio[21205]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 12:57:01 volumio volumio[21205]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 23 12:57:01 volumio volumio[21205]: errno: -111, Feb 23 12:57:01 volumio volumio[21205]: code: 'ECONNREFUSED', Feb 23 12:57:01 volumio volumio[21205]: syscall: 'connect', Feb 23 12:57:01 volumio volumio[21205]: address: '127.0.0.1', Feb 23 12:57:01 volumio volumio[21205]: port: 9879, Feb 23 12:57:01 volumio volumio[21205]: response: undefined Feb 23 12:57:01 volumio volumio[21205]: } Feb 23 12:57:01 volumio volumio[21205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 12:57:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113. Feb 23 12:57:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:57:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 12:57:01 volumio go-librespot[22025]: go-librespot daemon starting... Feb 23 12:57:02 volumio go-librespot[22026]: time="2026-02-23T12:57:02-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 12:57:02 volumio go-librespot[22026]: time="2026-02-23T12:57:02-06:00" level=debug msg="app state loaded" Feb 23 12:57:02 volumio go-librespot[22026]: time="2026-02-23T12:57:02-06:00" level=debug msg="stored credentials not found" Feb 23 12:57:02 volumio go-librespot[22026]: time="2026-02-23T12:57:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 12:57:02 volumio sudo[22035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 12:56' Feb 23 12:57:02 volumio sudo[22035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"