Feb 25 08:30:01 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:01 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Feb 25 08:30:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:01 volumio go-librespot[26755]: go-librespot daemon starting... Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="app state loaded" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=info msg="zeroconf server listening on port 42663" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="obtained new client token: AACHtAMu6RwroooO8AAgzzh5nEVlm3Iv/+nmxCLVoM/OKMQjUMhFtuQzGkuZEk/TM93iSwNi/itaFkiN2vJkhV9ytZiAysILPTbvXIfbNg7d52iRo5+kjGRePXLuX8BLH9SZh1gyHmKp+ap6Da+gEuW2Vu0l6Nf1mjl8YdpjCzSozR5aWwyPjI33PoGwShJ1OvT9QjkD67nHzZLiMLA4+Ab33D1r3Ce4r6VXKaadmgvPFh/BOYQAdBrsiQ==" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-06:00" level=debug msg="completed challenge" Feb 25 08:30:01 volumio go-librespot[26756]: time="2026-02-25T08:30:01-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 25 08:30:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 08:30:04 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 08:30:04 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:04 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99. Feb 25 08:30:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:05 volumio go-librespot[26777]: go-librespot daemon starting... Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=debug msg="app state loaded" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:05 volumio volumio[26193]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 25 08:30:05 volumio volumio[26193]: info: Completed starting MyVolumio Plugin Feb 25 08:30:05 volumio volumio[26193]: [Metrics] CommandRouter: 55s 350.46ms Feb 25 08:30:05 volumio volumio[26193]: info: CoreCommandRouter::volumiosetStartupVolume Feb 25 08:30:05 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 08:30:05 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 08:30:05 volumio volumio[26193]: info: CoreCommandRouter::Close All Modals sent Feb 25 08:30:05 volumio volumio[26193]: info: CoreCommandRouter::Close All Modals sent Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30: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 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30: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 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30: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 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=info msg="zeroconf server listening on port 35289" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=debug msg="obtained new client token: AACi8xsb07d0ISAOw4jSV23H8Jh8pAw41V0UNqL7tcJ0I07Q4UixtMTmM6jajC1aJccBSJmXor+NxbpLYG5i9d4HDJ3SmiqbuqXkSgEz0/9i/0gNAVOqgraWzuQviVSLDC2zLwfWGDrW2gGVndSPhJL+J3P7yEBrxDjoYL7A4ODuab1451s8gZNzSCuebbdjPWIUwvvuSJ3pC5HNp3dth2Hka6Z9Lgqv80CJpOdJJ1vXqULM0Y4XaIGctg==" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-06:00" level=debug msg="completed challenge" Feb 25 08:30:05 volumio go-librespot[26778]: time="2026-02-25T08:30:05-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 25 08:30:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:06 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 25 08:30:06 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 08:30:06 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 25 08:30:07 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:07 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100. Feb 25 08:30:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:08 volumio go-librespot[26791]: go-librespot daemon starting... Feb 25 08:30:08 volumio go-librespot[26792]: time="2026-02-25T08:30:08-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:08 volumio go-librespot[26792]: time="2026-02-25T08:30:08-06:00" level=debug msg="app state loaded" Feb 25 08:30:08 volumio go-librespot[26792]: time="2026-02-25T08:30:08-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:08 volumio go-librespot[26792]: time="2026-02-25T08:30:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-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 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-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 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-06:00" level=info msg="zeroconf server listening on port 46109" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-06:00" level=debug msg="obtained new client token: AACFeFDNiv2qczpLh+8738EGn6Iz27MPL8ewEP6Yl7oAwOlSgvprtsL3tAA48ZXJoKDJtoN765AxHrzxpVeP/5OhAqq9rLVPqDlest6PdwPqSsh3e8Uq9L8080nD62tMz6ec01/I5ETrziNcSxkaWcP6D5HnP/4MoWu0sRsemdGD4Kfejh/IxS0NWx0NlqbVe83ohTolaIafoXQHkIrmDj6kZNFXO+mG1kFUz1auXsMrhzxC/4oIXRitdw==" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30:09-06:00" level=debug msg="completed challenge" Feb 25 08:30:09 volumio go-librespot[26792]: time="2026-02-25T08:30: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 25 08:30:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:10 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:10 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:12 volumio volumio[26193]: info: BOOT COMPLETED Feb 25 08:30:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Feb 25 08:30:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:12 volumio go-librespot[26814]: go-librespot daemon starting... Feb 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-06:00" level=debug msg="app state loaded" Feb 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-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 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-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 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-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 25 08:30:12 volumio go-librespot[26815]: time="2026-02-25T08:30:12-06:00" level=info msg="zeroconf server listening on port 45337" Feb 25 08:30:13 volumio go-librespot[26815]: time="2026-02-25T08:30:13-06:00" level=debug msg="obtained new client token: AACUBcNnkXOQ6bwPZudI1kr9hupX2tp8leCpZXecoNUjyLtWKldI/IZYOSvfvR2bbXo1gOdHcUdFhJ8YlDlF31dHr9gjeeBNODCK4RMmiTURCAGriBw8bDqLUiTgWuzsXB43WdB6tiQWmIbz8NdsJxN133i8LCLiguG++JDuosIV+js8B8IkF2K1VTogMUKawZUMOUXPVDjNxLYsinzAseVyveL7O9Nm0r/qze596hFV6V1118ILx6Q=" Feb 25 08:30:13 volumio go-librespot[26815]: time="2026-02-25T08:30:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:13 volumio go-librespot[26815]: time="2026-02-25T08:30:13-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:13 volumio go-librespot[26815]: time="2026-02-25T08:30:13-06:00" level=debug msg="completed challenge" Feb 25 08:30:13 volumio go-librespot[26815]: time="2026-02-25T08:30: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 25 08:30:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:13 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:13 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:16 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:16 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Feb 25 08:30:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:16 volumio go-librespot[26825]: go-librespot daemon starting... Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=debug msg="app state loaded" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-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 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-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 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-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 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=info msg="zeroconf server listening on port 36605" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=debug msg="obtained new client token: AACPuYRV+QLCCsISbPp1m4bwMopP0/OnEQ601iRVpwkc5K7Qqv3YynLfe7euiNnP9qEBrI+xNrbMGuGfDPgizJpiffbYbkm53ZeKBs8Na0ZlKn1HjO+UJNulCB175manbBMlGurL8aUG7NFXoFwT0MVo03POyriCnJ5klnr7oJPpPv2rEy7E33c237U2mpedYq3D0m7f4BAZcIOmB4Z2DvrSmjM3bL3674/fUHl3fb8W+NU2J4lZHIboeQ==" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-06:00" level=debug msg="completed challenge" Feb 25 08:30:16 volumio go-librespot[26826]: time="2026-02-25T08:30:16-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 25 08:30:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:19 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:19 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Feb 25 08:30:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:20 volumio go-librespot[26834]: go-librespot daemon starting... Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=debug msg="app state loaded" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30: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-gew4.spotify.com:80]" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-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 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-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 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=info msg="zeroconf server listening on port 40231" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=debug msg="obtained new client token: AABEywgg6cNKZroI8vgF3MOQKsXFQYdsN3zUOqd6BK6pZTjY9TK6Dyfe046/M2bdiBwzAu58Z/2JGC2syRz3cR2RoOC1f53O5qLGg2esmDMDTzSAFZA3B7f1x8Tb6YQzWr4GvZXAU1ne4I74x47MNYzrRyTF2+52I2hvKJMcja++IC+GwLq/RweMVg0yl9qBdIW+PWDPF61MevVyd6n/GPLGcGeNVbyJhl/Ua+QRD/i5ag4HpBDgSLpDcg==" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-06:00" level=debug msg="completed challenge" Feb 25 08:30:20 volumio go-librespot[26835]: time="2026-02-25T08:30:20-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 25 08:30:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:22 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:22 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 104. Feb 25 08:30:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:23 volumio go-librespot[26859]: go-librespot daemon starting... Feb 25 08:30:23 volumio go-librespot[26860]: time="2026-02-25T08:30:23-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:23 volumio go-librespot[26860]: time="2026-02-25T08:30:23-06:00" level=debug msg="app state loaded" Feb 25 08:30:23 volumio go-librespot[26860]: time="2026-02-25T08:30:23-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:23 volumio go-librespot[26860]: time="2026-02-25T08:30:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-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 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-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 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-06:00" level=info msg="zeroconf server listening on port 36289" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-06:00" level=debug msg="obtained new client token: AAAZWzPYACLQU3KMyGTnuLoEQoYBtNZdNZaeQWM4mXV0EjrbSAGnelL8h1bbvvXy1uq7CCxfx6nGAUsD9sGWdeIBfCwNQz2QfU2E+6q9E3S2SRGOH1YT7oPobJ4enavZ2Vo9AeDN0fKgIa6ZptrF3Dc9hBGvBeKZ6ZBaiXoiD0wIlwNieggHaYpe7h/a8cRhMeD1GkQh1VYehuB9drWrtwshweTpcMdorRm4YV3grwroPzDQ1b6RnBxGuA==" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30:24-06:00" level=debug msg="completed challenge" Feb 25 08:30:24 volumio go-librespot[26860]: time="2026-02-25T08:30: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 25 08:30:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:25 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:25 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:27 volumio volumio[26193]: info: UPDATER: Scheduling automatic update Feb 25 08:30:27 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Feb 25 08:30:27 volumio volumio[26193]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Feb 25 08:30:27 volumio volumio[26193]: info: UPDATER: Auto update will take place at: Thu Feb 26 2026 05:04:03 GMT-0600 (Central Standard Time) Feb 25 08:30:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 105. Feb 25 08:30:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:27 volumio go-librespot[26867]: go-librespot daemon starting... Feb 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-06:00" level=debug msg="app state loaded" Feb 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-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 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-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 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-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 25 08:30:27 volumio go-librespot[26868]: time="2026-02-25T08:30:27-06:00" level=info msg="zeroconf server listening on port 40979" Feb 25 08:30:28 volumio go-librespot[26868]: time="2026-02-25T08:30:28-06:00" level=debug msg="obtained new client token: AADlyOZIsfY6mI6C4zsP+0dL64+ppB2jMT3QSgq3I5uY1XYV3JrjIgOjAmFZ+08PhQgACyBT1J/bhijchGHV9bxxqbplmazS4hqLd6qOFFEcAd2HqrNX9qoBI75AnEbjhaHnbuaTATMuTZRcqHcwS1UQVFavsgSP+eD9DR/VBtkXda/sgTArIR17eq5fUOiBTtYV9OM6lw5mdpVdO44Tv+ObpRTcVXDUsFVLKV9yrjDPQjRUPPZ/esQ=" Feb 25 08:30:28 volumio go-librespot[26868]: time="2026-02-25T08:30:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:28 volumio go-librespot[26868]: time="2026-02-25T08:30:28-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:28 volumio go-librespot[26868]: time="2026-02-25T08:30:28-06:00" level=debug msg="completed challenge" Feb 25 08:30:28 volumio go-librespot[26868]: time="2026-02-25T08:30: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 25 08:30:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:28 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:28 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:31 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:31 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 106. Feb 25 08:30:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:31 volumio go-librespot[26876]: go-librespot daemon starting... Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=debug msg="app state loaded" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-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 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-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 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-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 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=info msg="zeroconf server listening on port 39765" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=debug msg="obtained new client token: AACoS9EqgWQRdGtiSYZT8rxrf5hMZCozg0rdE50d769ynSYoWBERkKbxfm2CvkCmL0P93tW/+uTuEmY7kDxUgOvN62ye9ez65I5bS5q5/WqTRlO3Hgj980/8YyMCnzKIRzy2xgRlskmLagiXg3DdjGZ41N8/nv78vZdPVENxRoSuqZW3W7D3MWzaMsqijykdmn7oTVbI4SXdDxTkf3hKZh9aNIbFvBoBMwn0V7A2EBRFtYJjTJcFKRh/xA==" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-06:00" level=debug msg="completed challenge" Feb 25 08:30:31 volumio go-librespot[26877]: time="2026-02-25T08:30:31-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 25 08:30:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:34 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:34 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Feb 25 08:30:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:35 volumio go-librespot[26898]: go-librespot daemon starting... Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=debug msg="app state loaded" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-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 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-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 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=info msg="zeroconf server listening on port 38517" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=debug msg="obtained new client token: AACIUCrq6Mcm6X33QBRRN3q6+g6YPRmKKdeLQwovj9DEJFZKV7O2arZ4AvQ1inwEPM//iIb7lQBgR+M8xYLhynPlAONb3TaRVTyzp3u7sGTZASOvMzOdTOEz/K8qinByQFTtHe5q/xkT0Sfl/LkM4SGK7EIQeFXoBBou5mvctp0M3gXXPve1ZQvnS2g3mABDTnGQvGQLaEqcz6+xPxdM5gYLCJW67ZHedEhma+3P0sEUmYLYVYx8U1k1GQ==" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-06:00" level=debug msg="completed challenge" Feb 25 08:30:35 volumio go-librespot[26899]: time="2026-02-25T08:30:35-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 25 08:30:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:37 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:37 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Feb 25 08:30:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:38 volumio go-librespot[26909]: go-librespot daemon starting... Feb 25 08:30:38 volumio go-librespot[26910]: time="2026-02-25T08:30:38-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:38 volumio go-librespot[26910]: time="2026-02-25T08:30:38-06:00" level=debug msg="app state loaded" Feb 25 08:30:38 volumio go-librespot[26910]: time="2026-02-25T08:30:38-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:38 volumio go-librespot[26910]: time="2026-02-25T08:30:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-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 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-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 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-06:00" level=info msg="zeroconf server listening on port 33405" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-06:00" level=debug msg="obtained new client token: AADpNHM9o5OHMJynEhH5hmVJHTWR62nQbcCzsxcTnUDuv13NOB6x07RKHiIkzpHN2t7lp/66qNuh9U7MDFYeOS2lVWSWLBC9Uz3FGKdX44lrBtIxm92eou3TTS0mJ8wLgoviHEpeRzclBbO/YLT5etiXhe6yZhdo1nX0oh51UEmhBi8aM6srnFjG4y9YHWJ/N7ICfrAzvO2oMW31HSFK5d94I+oNq3i3co2sxgCTCq2i6CuuJejqQ14=" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30:39-06:00" level=debug msg="completed challenge" Feb 25 08:30:39 volumio go-librespot[26910]: time="2026-02-25T08:30: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 25 08:30:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:40 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:40 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Feb 25 08:30:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:42 volumio go-librespot[26931]: go-librespot daemon starting... Feb 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-06:00" level=debug msg="app state loaded" Feb 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-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 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-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 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-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 25 08:30:42 volumio go-librespot[26932]: time="2026-02-25T08:30:42-06:00" level=info msg="zeroconf server listening on port 42007" Feb 25 08:30:43 volumio go-librespot[26932]: time="2026-02-25T08:30:43-06:00" level=debug msg="obtained new client token: AADbTqQUh9O848bx72AllQbKkv0XhHoUjdnV/kOarn6s0NLOkbbK+jlFEe1L1eVisOogyDIR01GtEl2rlYQc/fioy+fZSU2/PYd6AQC0iHjUqorGnmL6W7P1gzQKADM3ZpNhrUABDn/X90J8KD6Z0wrxg4GjRZVZ5y+DVLoVA0T25s7hn9JlhXpBQB5LI1ViEvCAJ54tpa62zdGQtdyGQ+KlLDv6KS8W/9NSiX4tECNeutN1eh7hn8A=" Feb 25 08:30:43 volumio go-librespot[26932]: time="2026-02-25T08:30:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:43 volumio go-librespot[26932]: time="2026-02-25T08:30:43-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:43 volumio go-librespot[26932]: time="2026-02-25T08:30:43-06:00" level=debug msg="completed challenge" Feb 25 08:30:43 volumio go-librespot[26932]: time="2026-02-25T08:30: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 25 08:30:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:43 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:43 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Feb 25 08:30:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:46 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:46 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:46 volumio go-librespot[26939]: go-librespot daemon starting... Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=debug msg="app state loaded" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-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 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-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 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-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 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=info msg="zeroconf server listening on port 45761" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=debug msg="obtained new client token: AADpPJGv4swLFL+1y8Vu30fJ5HiLpPM6dfMQ780A0+gkV1nYM+hKzh6BxSCsOiyeKVN57rwuQldM9iYcWrrXGp5ud5k6XnSiILvb9fW3YFKh7WiIJwWPGi7ekca3E5Zj3PtQWo+yDctbaviCcZAicLu8eazaNQuN7+Wu0lcl8btf9VhXam3iofd+yBp1JRZXEDgMeikqYf8lcE2WvmoYiEgXFe5RN9MRnCr0OIej1wDCMnk0Pv4bmA4u1g==" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-06:00" level=debug msg="completed challenge" Feb 25 08:30:46 volumio go-librespot[26940]: time="2026-02-25T08:30:46-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 25 08:30:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:49 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:49 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Feb 25 08:30:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:50 volumio go-librespot[26947]: go-librespot daemon starting... Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=debug msg="app state loaded" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-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 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-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 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=info msg="zeroconf server listening on port 45487" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=debug msg="obtained new client token: AAAP8ZzU7bDhaBFacDuGImwXhCHpJggwtJaU3BIuRZZnFeW8qPwFEUU8i8LReHOJRvs4iDh05Q7vlObLrBAYlWF5uMW8TEAE3i7qGO7paMl3MRxCEgv1l3CxrZnE4iR/OU9NGmSDjBeuBoqKVzS9LsycpeQxlnnYCIW0qfGYYAvuBmGFwRyREIQM6jydfGh/7u3BxyWs/ptZe9A1s4AoFbY78HTPcgXhKpkRTjQgbZ1aekN5Ol67/jOWnA==" Feb 25 08:30:50 volumio go-librespot[26948]: time="2026-02-25T08:30:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:51 volumio go-librespot[26948]: time="2026-02-25T08:30:51-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:51 volumio go-librespot[26948]: time="2026-02-25T08:30:51-06:00" level=debug msg="completed challenge" Feb 25 08:30:51 volumio go-librespot[26948]: time="2026-02-25T08:30: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 25 08:30:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:52 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:52 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Feb 25 08:30:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:54 volumio go-librespot[26970]: go-librespot daemon starting... Feb 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-06:00" level=debug msg="app state loaded" Feb 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-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 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-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 25 08:30:54 volumio go-librespot[26971]: time="2026-02-25T08:30:54-06:00" level=info msg="zeroconf server listening on port 34167" Feb 25 08:30:55 volumio go-librespot[26971]: time="2026-02-25T08:30:55-06:00" level=debug msg="obtained new client token: AAClyd2rB/8DC13pkqE+jAm3R0CZTG6tc8kZOwUvGRMtq5sY4ZbdRHhSDeKAYm47lvfpFs5ZDvs152p40URSBlhLTNJs1eK4mCL0PFSazIl/+XatAcKrtJzrE2bfbyoZF+/jEXH5zB4bVzq/WIj+fqa7mU6sNPGvwKjfwjJMY7NbNYbHpx/RfgY7tiQ8iSeIhn6sRn6d3COPn5tXEf+pu+rSEv3olpDthM8bX+AyInce1jCxVA7nCGM=" Feb 25 08:30:55 volumio go-librespot[26971]: time="2026-02-25T08:30:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:55 volumio go-librespot[26971]: time="2026-02-25T08:30:55-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:55 volumio go-librespot[26971]: time="2026-02-25T08:30:55-06:00" level=debug msg="completed challenge" Feb 25 08:30:55 volumio go-librespot[26971]: time="2026-02-25T08:30: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 " Feb 25 08:30:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:30:55 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:55 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113. Feb 25 08:30:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:58 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:30:58 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:30:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:30:58 volumio go-librespot[26981]: go-librespot daemon starting... Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=debug msg="app state loaded" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=debug msg="stored credentials not found" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30: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 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30: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 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30: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 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=info msg="zeroconf server listening on port 46321" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=debug msg="obtained new client token: AACNWeQs2+gTkLzLa6gEMfFLMJzA0Ex00ucZ0rLhaHcaKr4TOakDbzTPiFq8PQuusDc5g/nVWQ446kSZTepW3HTwA0Y28Qv+/F5JGAS1SApoRszfMPQ4nneogmbFNVpqoQ/A0MachvNZxXM9ckfcyKldFgZrluVVLuPnVmmlbYoNd4mLajlYjIVmiL0M28gbxhCsX0YSdhfqjxND6IrUuzCHbrzg++SMsFdgz4e1pMHCKBlCfvkXvGgjgA==" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=debug msg="completed keyexchange" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30:58-06:00" level=debug msg="completed challenge" Feb 25 08:30:58 volumio go-librespot[26982]: time="2026-02-25T08:30: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 25 08:30:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:30:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:31:01 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:31:01 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:31:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114. Feb 25 08:31:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:31:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:31:02 volumio go-librespot[26989]: go-librespot daemon starting... Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=debug msg="app state loaded" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=debug msg="stored credentials not found" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31: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]" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31: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]" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31: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]" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=info msg="zeroconf server listening on port 45431" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=debug msg="obtained new client token: AADswUcDDHNDdMqEcMveIo/eFRp8ZqmMC+RA9LrMxlxVlu+FmmRHleI11HvEssNBKH62LQOY+ZTk9Yjr9tSioi8lFb8oCxHtsdIYCvtVKCFnPOjG9tSgaCK7TK7HffelqcSfHfbYkb3roggIPxNmIO+O6ifsSUGlWnQDn5G2vJFG9y8fMF6h4iIXpzlZiwMxwbxPWfCVGxA9ke9LqMNJCtbSpDOcAwYB/ZwX0nmut6wwawYIQGSAIUsu6Q==" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=debug msg="completed keyexchange" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31:02-06:00" level=debug msg="completed challenge" Feb 25 08:31:02 volumio go-librespot[26990]: time="2026-02-25T08:31: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 25 08:31:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:31:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:31:04 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:31:04 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:31:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115. Feb 25 08:31:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:31:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:31:05 volumio go-librespot[27011]: go-librespot daemon starting... Feb 25 08:31:05 volumio go-librespot[27012]: time="2026-02-25T08:31:05-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:31:05 volumio go-librespot[27012]: time="2026-02-25T08:31:05-06:00" level=debug msg="app state loaded" Feb 25 08:31:05 volumio go-librespot[27012]: time="2026-02-25T08:31:05-06:00" level=debug msg="stored credentials not found" Feb 25 08:31:05 volumio go-librespot[27012]: time="2026-02-25T08:31:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31: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]" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31: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]" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31: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]" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31:06-06:00" level=info msg="zeroconf server listening on port 38933" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31:06-06:00" level=debug msg="obtained new client token: AACVHpSx1V+c3AltLKcawMpSDTUYHf/8TjC7wxtTWp7EfNOIZkgn5Yq++nUIYGnuBudAgL2pKOBlgZpqkQ4jah+8Qm9fYzZ0K7AehIG0jwDVg0X+WrEyxWkOuf/8J8bdQUITsqzHQwGtQkX6Ndl6g4me6BiZHkER627ZhFK1CQzY4Omn7XQqeXciFuyb3IaKPeP47b5f17pa9Y0p6wEJ+aqCkA7deUdCmvHTsrWE33oxuJZe8CYo7gkgnw==" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31:06-06:00" level=debug msg="completed keyexchange" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31:06-06:00" level=debug msg="completed challenge" Feb 25 08:31:06 volumio go-librespot[27012]: time="2026-02-25T08:31: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 25 08:31:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:31:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:31:07 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:31:07 volumio volumio[26193]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 08:31:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116. Feb 25 08:31:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:31:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 08:31:09 volumio go-librespot[27019]: go-librespot daemon starting... Feb 25 08:31:09 volumio go-librespot[27020]: time="2026-02-25T08:31:09-06:00" level=info msg="running go-librespot 0.4.0" Feb 25 08:31:09 volumio go-librespot[27020]: time="2026-02-25T08:31:09-06:00" level=debug msg="app state loaded" Feb 25 08:31:09 volumio go-librespot[27020]: time="2026-02-25T08:31:09-06:00" level=debug msg="stored credentials not found" Feb 25 08:31:09 volumio go-librespot[27020]: time="2026-02-25T08:31:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 08:31:10 volumio volumio[26193]: info: Initializing connection to go-librespot Websocket Feb 25 08:31:10 volumio go-librespot[27020]: time="2026-02-25T08:31:10-06:00" level=debug msg="new websocket client" Feb 25 08:31:10 volumio volumio[26193]: info: Connection to go-librespot Websocket established Feb 25 08:31:13 volumio nmbd[1076]: [2026/02/25 08:31:13.228594, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 25 08:31:13 volumio nmbd[1076]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.221 for name WORKGROUP<1d>. Feb 25 08:31:13 volumio nmbd[1076]: This response was from IP 192.168.1.112, reporting an IP address of 192.168.1.112. Feb 25 08:31:13 volumio volumio[26193]: info: Getting Spotify volume Feb 25 08:31:13 volumio volumio[26193]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 08:31:13 volumio volumio[26193]: info: CoreCommandRouter::volumioGetState Feb 25 08:31:13 volumio volumio[26193]: info: CorePlayQueue::getTrack 0 Feb 25 08:31:13 volumio volumio[26193]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 08:31:13 volumio volumio[26193]: SPOTIFY: SPOTIFY VOLUME undefined Feb 25 08:31:13 volumio volumio[26193]: SPOTIFY: VOLUMIO VOLUME 100 Feb 25 08:31:13 volumio volumio[26193]: info: Aligning Spotify Volume to Volumio Volume Feb 25 08:31:13 volumio volumio[26193]: info: CoreCommandRouter::volumioGetState Feb 25 08:31:13 volumio volumio[26193]: info: CorePlayQueue::getTrack 0 Feb 25 08:31:13 volumio volumio[26193]: info: Setting Spotify Volume from Volumio: 100 Feb 25 08:31:15 volumio volumio[26193]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Feb 25 08:31:15 volumio volumio[26193]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=info msg="zeroconf server listening on port 40433" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="obtained new client token: AAD4jAnYsnCbeHeIAZd5zYKXxBfwkK3jmLArS2l8d/984a0hNF3ikHtoJ35wdUQ9542VtxAlTc7dESgXIi6p1gWkQk6MD6dxDMEvq+CH/mbmqZongyP0zqGjpeRg8qoMFFRgtfmpGrYdhFzw1yRr2hCECKoU5Bb57MbT0aVSCVxqivy8EHauHZpUh15ZX0S4x5KK1e3/LxTvyLOpX2tdNX/8xkoMvdro71fNNVyuVTGN2o8oVrymJczOxQ==" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="completed keyexchange" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=debug msg="completed challenge" Feb 25 08:31:15 volumio go-librespot[27020]: time="2026-02-25T08:31:15-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 08:31:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 08:31:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 08:31:15 volumio volumio[26193]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 25 08:31:15 volumio volumio[26193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 08:31:15 volumio volumio[26193]: Error: socket hang up Feb 25 08:31:15 volumio volumio[26193]: at connResetException (node:internal/errors:720:14) Feb 25 08:31:15 volumio volumio[26193]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 08:31:15 volumio volumio[26193]: at Socket.emit (node:events:526:35) Feb 25 08:31:15 volumio volumio[26193]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 08:31:15 volumio volumio[26193]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 08:31:15 volumio volumio[26193]: code: 'ECONNRESET', Feb 25 08:31:15 volumio volumio[26193]: response: undefined Feb 25 08:31:15 volumio volumio[26193]: } Feb 25 08:31:15 volumio volumio[26193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 08:31:16 volumio sudo[27059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 08:30' Feb 25 08:31:16 volumio sudo[27059]: 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"