Dec 10 00:09:00 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:00 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 10 00:09:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::volumioGetBrowseSources Dec 10 00:09:00 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 00:09:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:00 volumio go-librespot[18643]: go-librespot daemon starting... Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="app state loaded" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=info msg="zeroconf server listening on port 36515" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="obtained new client token: AACt7iAgrkUfbiqNQxTsOTYB2NaBfuCoUKX+5UTu7Xeh7L3MvsHCkgXg/Fu7ixloVbWAXsqGRwgPS70FML4v/te9T4c0tFtS+ybxr25H89vFdzTPOquKg6LxaVv6wFwvePzqYMxWHTxvuqf9mkqnVPBeLX4PYZusZAe+6LR79ZIvKKEDNg/T2Eos4D6sVucHng3NWPgsgb3LRhVa25i2DonE44DWNpY9su2G1/lwnTTOj+rKP8iE/xF7Cw==" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=debug msg="completed challenge" Dec 10 00:09:00 volumio go-librespot[18644]: time="2025-12-10T00:09:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:01 volumio volumio[18033]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 10 00:09:01 volumio volumio[18033]: info: Completed starting MyVolumio Plugin Dec 10 00:09:01 volumio volumio[18033]: [Metrics] CommandRouter: 53s 886.53ms Dec 10 00:09:01 volumio volumio[18033]: info: CoreCommandRouter::volumiosetStartupVolume Dec 10 00:09:01 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 00:09:01 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 00:09:01 volumio volumio[18033]: info: CoreCommandRouter::Close All Modals sent Dec 10 00:09:01 volumio volumio[18033]: info: CoreCommandRouter::Close All Modals sent Dec 10 00:09:02 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 10 00:09:02 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 00:09:02 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 10 00:09:03 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:03 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 10 00:09:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:04 volumio go-librespot[18658]: go-librespot daemon starting... Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=debug msg="app state loaded" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-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]" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-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]" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-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]" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=info msg="zeroconf server listening on port 45877" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=debug msg="obtained new client token: AAB+nugIvbI6Cn7qG84dEPr7UoIfXPjN7Ooe2SZBkj9yxn0HxjR2d8h2Jur+sbZI8oB5lLU/+i9pdR0OKP8R1g+dkvH2sZBn223Ay8K5tNvsLDIRaQBHyMQ2YJXZ2Peu/gv06hs2YINVk4QNN4q5otl7dk8ZL6F6a9d8c5ZsgP3mGRtXKZEc0p5j2Hf860+jh18IY5T7hu+Wp6HPytC6EQSLGpYmWdOADN75eYZP0uZmCt4I5f61qctTGQ==" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=debug msg="completed challenge" Dec 10 00:09:04 volumio go-librespot[18659]: time="2025-12-10T00:09:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:06 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:06 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 10 00:09:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:07 volumio go-librespot[18667]: go-librespot daemon starting... Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-06:00" level=debug msg="app state loaded" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-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]" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-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]" Dec 10 00:09:07 volumio go-librespot[18668]: time="2025-12-10T00:09:07-06:00" level=info msg="zeroconf server listening on port 46339" Dec 10 00:09:08 volumio go-librespot[18668]: time="2025-12-10T00:09:08-06:00" level=debug msg="obtained new client token: AACMkEQk1g36cTbXiDmhsZNYicCK0+5/MLDNyE8HfS+aUUH+AEhlxveiJFTQYNspPGrXey9dJW/O17jzED454xd6WAmD5LWb7sKmqeCA1toRYV7EmMHCUmK1VNWp7AdupNml2QX2+Gco0RlL/DZmWzMwG2f82KDQmCp9R7RAcy+3Z1059htJkiQ/I8UKTphjMAeFMCKYcJmDKbGP3LGye5zACbm4YbJWnklSM2G+JkkeEJVkjHOb7xg=" Dec 10 00:09:08 volumio go-librespot[18668]: time="2025-12-10T00:09:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:08 volumio volumio[18033]: info: BOOT COMPLETED Dec 10 00:09:08 volumio go-librespot[18668]: time="2025-12-10T00:09:08-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:08 volumio go-librespot[18668]: time="2025-12-10T00:09:08-06:00" level=debug msg="completed challenge" Dec 10 00:09:08 volumio go-librespot[18668]: time="2025-12-10T00:09:08-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:09 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:09 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 10 00:09:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:11 volumio go-librespot[18692]: go-librespot daemon starting... Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="app state loaded" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-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]" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-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]" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=info msg="zeroconf server listening on port 45747" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="obtained new client token: AADuMOIR87IUEiTBA/y7UQ5HKuuFBwfw80kDG3y+yOMxEVA4GEBwtg15ihn0/Eh3Dk4ztghdZNhpPlqIc4mg1/9u92efzSKwZN0G6OzG4OKRj9wcf8x9WHir1KrR40EXuqGCK1oLLN29CbFsDS6jNqNu4tEVVFDWpfuE89p3XP08sK0DMnkwyFjj2coau+Fl0GX55kb00BjVlTK65ERbvaU6+T9h2AGdSyw+LyJkUeHns+Kx2KqBCZOaiQ==" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:11 volumio go-librespot[18693]: time="2025-12-10T00:09:11-06:00" level=debug msg="completed challenge" Dec 10 00:09:12 volumio go-librespot[18693]: time="2025-12-10T00:09:12-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 " Dec 10 00:09:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:12 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:12 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:15 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:15 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 10 00:09:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:15 volumio go-librespot[18700]: go-librespot daemon starting... Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=debug msg="app state loaded" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09: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]" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09: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]" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09: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]" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=info msg="zeroconf server listening on port 42407" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=debug msg="obtained new client token: AAD3XynDQJVbMpTWszTQ3UMGpv7bCfmT4jARPbEecM0A0blvUNVcpSQ0wSjujA3X3m58QQvr9LXZd+Muroyz8WIjaSiS/63cxxTTuKxoctBeXjPfpxOJjjdBrUYGH1HCq1RsNTwXbnB+RHSrKzTz9s1GhlHTzusljKLVgQ8lRLc5zroQYNF75YK4WciW3W9YlAV8mWnYeI/OQ1e/S0VtVUZURDksZJia+Q7EJemgEUrg1eHxjzLemQG02w==" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09:15-06:00" level=debug msg="completed challenge" Dec 10 00:09:15 volumio go-librespot[18701]: time="2025-12-10T00:09: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 " Dec 10 00:09:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:18 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:18 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 10 00:09:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:18 volumio go-librespot[18708]: go-librespot daemon starting... Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=debug msg="app state loaded" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-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]" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-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]" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-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]" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=info msg="zeroconf server listening on port 42051" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=debug msg="obtained new client token: AABLExw0PopBZ+4ILVwuOrO11VykIIDz+eennDzlXIk1KQbqDSCP9BMiKPobh+nFTUXAi8pfm8H7ZZZRirdpjGTG/TEurriChH7uwTzS5HSXqLSbjGiP5Jk1bWEEl3uTUWCti/c+RdVxJedR1WCvvKKLUXCNeFnjciUS1ntmWDstAzHirOae4CXO8nXENiGQiCUZzBib59hPCcn74Ee9Jgs9Uq5cw4dsanVqyeNSeJQMC+NC5U/El2bLFg==" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=debug msg="completed challenge" Dec 10 00:09:19 volumio go-librespot[18709]: time="2025-12-10T00:09:19-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:21 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:21 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:22 volumio volumio[18033]: info: UPDATER: Scheduling automatic update Dec 10 00:09:22 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Dec 10 00:09:22 volumio volumio[18033]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Dec 10 00:09:22 volumio volumio[18033]: info: UPDATER: Auto update will take place at: Thu Dec 11 2025 03:44:58 GMT-0600 (Central Standard Time) Dec 10 00:09:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 10 00:09:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:22 volumio go-librespot[18730]: go-librespot daemon starting... Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-06:00" level=debug msg="app state loaded" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-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]" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-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]" Dec 10 00:09:22 volumio go-librespot[18731]: time="2025-12-10T00:09:22-06:00" level=info msg="zeroconf server listening on port 36859" Dec 10 00:09:23 volumio go-librespot[18731]: time="2025-12-10T00:09:23-06:00" level=debug msg="obtained new client token: AAB78/ago3dxcoWuVNK5I3c6+l/haPaROIu6SG79guENSZm1DVc+xafvBfi25D8zlzr9GxXEY76cVU9owQy4rlpkc8C99/oTSZonqXyC2fHVi869irzrb24JRZC09GbPy6KN1L6J4E9Yy0iFayFYyrPeTArY/+BJFcO8cw2eEwped/++ubG9/orofaFhEOpw0noAIEB/jAtFODEck+M6V1HLzMWd2u5q5kw+LlYO6DQ7xApZce3bpRo=" Dec 10 00:09:23 volumio go-librespot[18731]: time="2025-12-10T00:09:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:23 volumio go-librespot[18731]: time="2025-12-10T00:09:23-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:23 volumio go-librespot[18731]: time="2025-12-10T00:09:23-06:00" level=debug msg="completed challenge" Dec 10 00:09:23 volumio go-librespot[18731]: time="2025-12-10T00:09:23-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 " Dec 10 00:09:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:24 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:24 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 10 00:09:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:26 volumio go-librespot[18739]: go-librespot daemon starting... Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="app state loaded" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-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]" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-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]" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=info msg="zeroconf server listening on port 34281" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="obtained new client token: AAAoJqnJLqrexXGvZzFQO0PzkxHjNtZJqSPwz6oJmNC1Pc/MuJ+mo8YwFuCGEGfds5S0Du9AY4t0e2abRs1icamX2fgpUUb1LsuOZ/k/8K6AXkFRVucYBoNDqJxi/vXx7nnRjqFwnKaotlVRMGkLjKCS+UJCMsDK4V4FakvSoB8JyswcPvdQaJLnV+VF3+4yrc4t5OjNV818Q30x+CWCHCS8/yG2HPV1g4u3OSvgPA5HH+mELA7Gbhn6sw==" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:26 volumio go-librespot[18740]: time="2025-12-10T00:09:26-06:00" level=debug msg="completed challenge" Dec 10 00:09:27 volumio go-librespot[18740]: time="2025-12-10T00:09:27-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 " Dec 10 00:09:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:27 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:27 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:30 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:30 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 10 00:09:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:30 volumio go-librespot[18761]: go-librespot daemon starting... Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="app state loaded" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-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]" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-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]" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=info msg="zeroconf server listening on port 35381" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="obtained new client token: AABb4SkPxgp71XzgXyKm1y84Cijds0HAnn9G3ItqOw8rtjqFvyMsmvGean2qP7NVk+DzB7PUmB2A/qL8X5B4Iq5qdSNdfkW5ne9xSr2gDbWR9vHiDSF3wB9z01eQMpXjElcv/FpCQoALQKh9K66cMuXnF/HLWJK9pabdCcv+ad7N6Xma2qa68CZ3tEWLUrZu95p2tby2LeJQkdzLPzu8gxoJtYKlnyiX3pKDBjELRHxWTCwoXY3MSN0ybw==" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=debug msg="completed challenge" Dec 10 00:09:30 volumio go-librespot[18762]: time="2025-12-10T00:09:30-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:33 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:33 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 10 00:09:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:33 volumio go-librespot[18772]: go-librespot daemon starting... Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=debug msg="app state loaded" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-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]" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-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]" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-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]" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=info msg="zeroconf server listening on port 43207" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=debug msg="obtained new client token: AACDAqp1EbhsJvh0qWU0DnSM4Xt3zCDZl516h78dSn3ly3Mu0I71/RBtZCcH4wcJ5GNzE0HFpev+GJnBq3705j6TMUlcO1NAIY5eABPblo9fyGx7qcqOqCG8hKzNwyKPeZGe76v5ZyZiloPutkD1juUTnr1gi22v3voduRDgK4l9KZo6LZlSziKHNmF/jpNqQj+O8bNAfY33EazTsXV7L+sTNYu3q6ntXs30unk04G39bFYHnf4JRN3n7g==" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=debug msg="completed challenge" Dec 10 00:09:34 volumio go-librespot[18773]: time="2025-12-10T00:09:34-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:36 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:36 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 10 00:09:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:37 volumio go-librespot[18780]: go-librespot daemon starting... Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=debug msg="app state loaded" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 10 00:09:37 volumio go-librespot[18781]: time="2025-12-10T00:09:37-06:00" level=info msg="zeroconf server listening on port 46049" Dec 10 00:09:38 volumio go-librespot[18781]: time="2025-12-10T00:09:38-06:00" level=debug msg="obtained new client token: AABUML4R0yyfwEMjnwDGrlLSwQ3Hg5gNCxFQdR9PGMVcTg/5CGuWb8EOaCy7kskBf2dOV+LmeCTozMOrx3qS58vLFb/UuejRAKuYZTqXotwbgXr5jd7q9VPyzDHOA4CEvwMNjxY/Vxg2bOqB0V7Ayc6/Gnl38OqK4ZHJWSXFGHqmNG1Lt9FQoqPBUYb8Mjw3ZrTWuL6e5eCSv4KVFwajyjd3K0TY27wNLZbI/jYMNw9wKuOG+GvgwI0=" Dec 10 00:09:38 volumio go-librespot[18781]: time="2025-12-10T00:09:38-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:38 volumio go-librespot[18781]: time="2025-12-10T00:09:38-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:38 volumio go-librespot[18781]: time="2025-12-10T00:09:38-06:00" level=debug msg="completed challenge" Dec 10 00:09:38 volumio go-librespot[18781]: time="2025-12-10T00:09:38-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 " Dec 10 00:09:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:39 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:39 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 10 00:09:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:41 volumio go-librespot[18802]: go-librespot daemon starting... Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="app state loaded" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-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]" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-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]" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=info msg="zeroconf server listening on port 33549" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="obtained new client token: AABbJJi1OilbzQyCxEkUNeElS0qpq3bI7A0Rs+y6pCTMmtnoeBj5wDh3PY8uJ/ph7NTmezrmROKwrGtaxFqkA1dLOWiRvQn7THuaEfIbcrvVgSFbybP902PMSOUEhWqFko8j6PZKK8Xw2zazNhYUyVg21bO+4EmaQfxPuTRAFSvjxR7KlQQJt2D54D5OCjgU6THc+ZpCQZyYbWx3+5oNh1azkBcYcoRwcx2AucSUC9aYKHRYIK1vyi6Rgw==" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-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" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:41 volumio go-librespot[18803]: time="2025-12-10T00:09:41-06:00" level=debug msg="completed challenge" Dec 10 00:09:42 volumio go-librespot[18803]: time="2025-12-10T00:09:42-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 " Dec 10 00:09:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:42 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:42 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 10 00:09:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:45 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:45 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:45 volumio go-librespot[18810]: go-librespot daemon starting... Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="app state loaded" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-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]" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-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]" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=info msg="zeroconf server listening on port 44601" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="obtained new client token: AADSuIcnOkmYOrcNLv+YazYbGgbHeCQ+e7ZflrvkoR1kVwXTl4+XOTTNiFstthcAugUZwYG1keW5GBVSSTJkv6qFPYJ+zweO60MHEjtk5iASEjazwHINT0ArnSN3VdBA7KErm1wjkdXqOWaIjSXNNDlUD14DHUbtZoHxPfmadvNhiGsi2lNZD2ZF2UjrjeP8TX9qigWPrHHgPodFWZYPMwjQFqwaOOqyYMCnjUxn7GHg56KETICdeHtHwg==" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=debug msg="completed challenge" Dec 10 00:09:45 volumio go-librespot[18811]: time="2025-12-10T00:09:45-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:48 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:48 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 10 00:09:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:48 volumio go-librespot[18819]: go-librespot daemon starting... Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=debug msg="app state loaded" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-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]" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-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]" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-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]" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=info msg="zeroconf server listening on port 38463" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=debug msg="obtained new client token: AAApCt0OJc6t/LK26aBnzpTqRxmhKxhp4Qnkwoe5cIfTSs8HWpb7qmn+0TVbUPhlc2GZmddE97hiprEv/pmgO6SEeHmKxPTBYUY/KnmHTL4hXMcRQNyDFwIVgMDNptJWoXm+WbJSKcClKNtWT4WyR8AgwkohIL8JooER/C48o9mnoItnhfKvwFAfM7m1Ul6VTtr7/K/AlgF3ShofaEWsp28A5zqpEJwyBmNPI3tvuL1QaVWLR2rmcBIBPg==" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=debug msg="completed challenge" Dec 10 00:09:49 volumio go-librespot[18820]: time="2025-12-10T00:09:49-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:51 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:51 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Dec 10 00:09:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:52 volumio go-librespot[18844]: go-librespot daemon starting... Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-06:00" level=debug msg="app state loaded" Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-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]" Dec 10 00:09:52 volumio go-librespot[18845]: time="2025-12-10T00:09:52-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]" Dec 10 00:09:53 volumio go-librespot[18845]: time="2025-12-10T00:09:53-06:00" level=info msg="zeroconf server listening on port 43963" Dec 10 00:09:53 volumio go-librespot[18845]: time="2025-12-10T00:09:53-06:00" level=debug msg="obtained new client token: AABbdST3wLw4BBQaPuZItBMk8Krm4IadSxIgoA0A165QDrKLgtUcFlgso6h0vGfjhaqqLGMjMxi6mmTJlDxpQYB+/5Zke7VOdPU8PbDvKIZth8CnzIEDfyfDtMquvoydLvx0WJeYiqsh/8OwO+KtebrwCWzl4I9pbALIofWmm/rHH8cfvaFJHDcmhipmb/wYn/sxqANVZeTLuJwh1D7CX1tnThTfB0BrzFmhhBD7h4spiBm11v68FZs=" Dec 10 00:09:53 volumio go-librespot[18845]: time="2025-12-10T00:09:53-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:53 volumio go-librespot[18845]: time="2025-12-10T00:09:53-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:53 volumio go-librespot[18845]: time="2025-12-10T00:09:53-06:00" level=debug msg="completed challenge" Dec 10 00:09:53 volumio go-librespot[18845]: time="2025-12-10T00:09:53-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 " Dec 10 00:09:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:54 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:54 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:09:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 10 00:09:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:09:56 volumio go-librespot[18852]: go-librespot daemon starting... Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="app state loaded" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="stored credentials not found" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-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]" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-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]" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=info msg="zeroconf server listening on port 42395" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="obtained new client token: AAALxYogyxrnFistb1vhp/H6wIGiIGNrtwDSkz2piglxvLrBtu27ZHmslK7q8PNerizlV9wnpWQ1MmiEG7dszCcdfM0HNwOPi5NHRSC1fN11hbQbG3q5wVbOQbShAQawfDfYqu98nD7S981LhKIil+TKX94QheBwTfLqcuXW3Djo+Vw9n5jRZujL7QPYCtiYaZKypDt2psK06lbMhHAqiybDtsyLA2ynHqkTKEcd21q6qcXx/4fpXoTr/g==" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="completed keyexchange" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=debug msg="completed challenge" Dec 10 00:09:56 volumio go-librespot[18853]: time="2025-12-10T00:09:56-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:09:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:09:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:09:57 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:09:57 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 10 00:10:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:00 volumio go-librespot[18874]: go-librespot daemon starting... Dec 10 00:10:00 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:00 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="app state loaded" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-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]" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-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]" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=info msg="zeroconf server listening on port 44585" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="obtained new client token: AABOREVVQ2/KpXlOgLB6k2P9wBbkWmpMJOmsxJqx+lK2wahfHsoomVdCi9t8/RHzYTBNKXQlG8a96b4fWytP3ThZtfMTRzm9EGp7eDKvvX/UKFd/2Sfal144kZQn9uGl8cAkZarWxeR9dUJmJhLSieS2+/dCuiUNI1z04E5kCE8htia0g/63rtWuhwiNqDNNMFxLsapfk63K6Lkx0JcdYEOnZ5v3tlf5ciQPibtZbsfsNffbITfZsE9bgg==" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-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" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=debug msg="completed challenge" Dec 10 00:10:00 volumio go-librespot[18875]: time="2025-12-10T00:10:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:10:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:03 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:03 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 10 00:10:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:03 volumio go-librespot[18882]: go-librespot daemon starting... Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=debug msg="app state loaded" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-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]" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-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]" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-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]" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=info msg="zeroconf server listening on port 43939" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=debug msg="obtained new client token: AAAL2rJrz5/exSa47zXYru2tFaoADroCYS3TPWdBgh69qs/S/oiLtRzsXkPeA7RJo7lA+d/B/WqawdW8+/PB5vX5ZgtoIVlmdttINPcDdD27vJlJnIdHcBY5LD4yDGZQSm0FJqm8rtDh46XZoJuA2gXlfACDxJHEdLBwjAYPtjGjzkaMTtbGOx/smlpT1hMa3dghMqepqIQnjZnFvQkbhxtjSFyyVNXW2ZuUlZUmLk4WBb6ysEj0xvpCfg==" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=debug msg="completed challenge" Dec 10 00:10:04 volumio go-librespot[18883]: time="2025-12-10T00:10:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:10:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:06 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:06 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 10 00:10:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:07 volumio go-librespot[18892]: go-librespot daemon starting... Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-06:00" level=debug msg="app state loaded" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-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]" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-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]" Dec 10 00:10:07 volumio go-librespot[18893]: time="2025-12-10T00:10:07-06:00" level=info msg="zeroconf server listening on port 38241" Dec 10 00:10:08 volumio go-librespot[18893]: time="2025-12-10T00:10:08-06:00" level=debug msg="obtained new client token: AADcKWrPwDawdPsckoz57WtN7mK34stqgY1W3aPmQZDvA3QWWqkqI0lf3/qMBEqRuqMMEF9wXZZTVN2vpiIlDJyQEwu444woOtdsljzRRwyOYe2bH6F0qjLrfubP8Vr87a28hMjCGtpWTpUU+Fs0hBTm0F55kPl/B0ntPU7/C9xU0oWMxeiJTPUYTykRrv0UJB6ObyJhmMrjgPqdC1N06NhnH8N3ijIZw3VUZXdEIHmmSuDFVzkrqso=" Dec 10 00:10:08 volumio go-librespot[18893]: time="2025-12-10T00:10:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:08 volumio go-librespot[18893]: time="2025-12-10T00:10:08-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:08 volumio go-librespot[18893]: time="2025-12-10T00:10:08-06:00" level=debug msg="completed challenge" Dec 10 00:10:08 volumio go-librespot[18893]: time="2025-12-10T00:10:08-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:10:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:09 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:09 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Dec 10 00:10:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:11 volumio go-librespot[18918]: go-librespot daemon starting... Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="app state loaded" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-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]" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-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]" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=info msg="zeroconf server listening on port 35449" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="obtained new client token: AADTGGyx3UZu+uTKpNJJ6oZFIlHgpZaOmWOpT/9k3HZBFIXW6StSq7HNg/fqdYR8+QhRJr/Cf74YAVANBG0U0VDLQjIhYIYwftYOOaNOwpGaxHPACaMs9wWKqmkWNotCEj39+eSIoXAxVXMrbNWDl73Snam/9hGwZRXaWzafL5NBZxR0uyOB7h0XQJEMOihb8gwqKRMo2IwEtNu6Az1X8O1DTdZI6zi+kk+D5kfT+WFcJnQ2Vd1q80R8MA==" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:11 volumio go-librespot[18919]: time="2025-12-10T00:10:11-06:00" level=debug msg="completed challenge" Dec 10 00:10:12 volumio go-librespot[18919]: time="2025-12-10T00:10:12-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 " Dec 10 00:10:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:12 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:12 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Dec 10 00:10:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:15 volumio go-librespot[18926]: go-librespot daemon starting... Dec 10 00:10:15 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:15 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="app state loaded" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-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]" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-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]" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=info msg="zeroconf server listening on port 41959" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="obtained new client token: AABNO6Pg8HqOpt6sZyTfzFB9y/g8PpzPW2IWBrCNxH5ZJ23V4zwIROIXugbx6fdacM0LBqyWw8VWuobU8lK8dBuTrKb4K9rjW1xW1lwpHABVdRRLprlWuzZNcY9UNAfitQI5GNKWuQCANpaA2awohg7JNy2Gu0wyUCalpWjFuJklgUeyPDUcveizki2Nv5S7G5rpF29eDlvfVNKeyJFlHMzs64uqbS7ixVESnSfsi0vLtmHkmSCsa8UlPQ==" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10:15-06:00" level=debug msg="completed challenge" Dec 10 00:10:15 volumio go-librespot[18927]: time="2025-12-10T00:10: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 " Dec 10 00:10:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:18 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:18 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Dec 10 00:10:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:18 volumio go-librespot[18934]: go-librespot daemon starting... Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=debug msg="app state loaded" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-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]" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-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]" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-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]" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=info msg="zeroconf server listening on port 35303" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=debug msg="obtained new client token: AADwcHS1Sv1Cqssw2g0cGQac7M4MdwpYCj6RIYWUAhLLV1ekG4Jlb8Xxv466DUpFSS21I0ioHR9ewlRnFXuLbB3yeTS1YORUcx+gydfhOJ28EQ3DILrN6Zb6ijSocTinLz6yb0zw4+1i91jNErvseK3P894gNqS0iLfIaL+B+MVYCl0WqeqEg5iCo0GWeE94CW7PxskcNASJQl56CnhVnBajJCB1AT8cpIYM8NbJ9CE5leYDmQDUbh2ZHA==" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=debug msg="completed challenge" Dec 10 00:10:19 volumio go-librespot[18935]: time="2025-12-10T00:10:19-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:10:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:21 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:21 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Dec 10 00:10:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:22 volumio go-librespot[18956]: go-librespot daemon starting... Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-06:00" level=debug msg="app state loaded" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-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]" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-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]" Dec 10 00:10:22 volumio go-librespot[18957]: time="2025-12-10T00:10:22-06:00" level=info msg="zeroconf server listening on port 40045" Dec 10 00:10:23 volumio go-librespot[18957]: time="2025-12-10T00:10:23-06:00" level=debug msg="obtained new client token: AACZDVjlBbedYyEyhU5uERG4ynM54RcpLZFJYHiQ7Uc+34okdnOH2JJba6lg2PPOjAvjMjdopwNYa9hkER8M11xYkwmj2qdAxfdVdaUthcljfcvIr7jCzy7Ym5Z0id0KVE7zDhvpHis2yz8SqktKQEOA/bkTU5Tc2GpfpxYQxWf4ClWogMCQxbOwMKzJfSySohYB582ZAvczAKhADVw2RjFqp3FmcRgMMwjWQ1Ozb/2YmyM/+3dD/dk=" Dec 10 00:10:23 volumio go-librespot[18957]: time="2025-12-10T00:10:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:23 volumio go-librespot[18957]: time="2025-12-10T00:10:23-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:23 volumio go-librespot[18957]: time="2025-12-10T00:10:23-06:00" level=debug msg="completed challenge" Dec 10 00:10:23 volumio go-librespot[18957]: time="2025-12-10T00:10:23-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 " Dec 10 00:10:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:24 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:24 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Dec 10 00:10:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:26 volumio go-librespot[18965]: go-librespot daemon starting... Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="app state loaded" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-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]" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-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]" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=info msg="zeroconf server listening on port 45127" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="obtained new client token: AADkMqohYUpBWTG7ke0YZGKglCZ7KgjVGFcnOdNA60fpY9yrDdXd823GT+kfjRCo99BJLsZv0WskdXcrMaqPGEtJLu3/vcfg1Lq2ju+zOGgJCwOXBMzdXbaARWGp8ZoM3wehrA50TMR/fYIf8OaL+SGzmx2onLuBsnUXAPmxq8UYbr+5Nqh7qJrXnd8bMKAKeBWefOLYavlRr/GLI1gR6IBea7isd/LZeNYNUI2orM9zAGtbhmu6okgxbg==" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=debug msg="completed challenge" Dec 10 00:10:26 volumio go-librespot[18966]: time="2025-12-10T00:10:26-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:10:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:27 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:27 volumio volumio[18033]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Dec 10 00:10:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:30 volumio go-librespot[18987]: go-librespot daemon starting... Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="app state loaded" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:30 volumio volumio[18033]: info: Initializing connection to go-librespot Websocket Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="new websocket client" Dec 10 00:10:30 volumio volumio[18033]: info: Connection to go-librespot Websocket established Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-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]" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-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]" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=info msg="zeroconf server listening on port 38867" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="obtained new client token: AADWAS3nt3dnjAx4jTGTZjYs2CVErMXy+eIhIg7rHJHAbRWYGD73o0sn1alK11L2o/GXb9x/rBwUg0MmIgpC5jcw35X1sKCdarKvvEsmPH5PsWS4gx/744wJUKL7bZynLkhoi/wTq+EYzWLlrPCQYBedzR7ocalv8aNj6zxZUfsrQP+9vtDVQbisVGlLHX24Wqe7+S6B5aIyKVgv1YOepNweBMCY5TmRhbw6WCft2o1ZdOa5DkIgAGK63w==" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="completed keyexchange" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=debug msg="completed challenge" Dec 10 00:10:30 volumio go-librespot[18988]: time="2025-12-10T00:10:30-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 10 00:10:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 10 00:10:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 10 00:10:30 volumio volumio[18033]: info: Connection to go-librespot Websocket closed Dec 10 00:10:33 volumio volumio[18033]: info: Getting Spotify volume Dec 10 00:10:33 volumio volumio[18033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 00:10:33 volumio volumio[18033]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 10 00:10:33 volumio volumio[18033]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 10 00:10:33 volumio volumio[18033]: errno: -111, Dec 10 00:10:33 volumio volumio[18033]: code: 'ECONNREFUSED', Dec 10 00:10:33 volumio volumio[18033]: syscall: 'connect', Dec 10 00:10:33 volumio volumio[18033]: address: '127.0.0.1', Dec 10 00:10:33 volumio volumio[18033]: port: 9879, Dec 10 00:10:33 volumio volumio[18033]: response: undefined Dec 10 00:10:33 volumio volumio[18033]: } Dec 10 00:10:33 volumio volumio[18033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 00:10:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Dec 10 00:10:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 10 00:10:34 volumio go-librespot[19010]: go-librespot daemon starting... Dec 10 00:10:34 volumio go-librespot[19011]: time="2025-12-10T00:10:34-06:00" level=info msg="running go-librespot 0.4.0" Dec 10 00:10:34 volumio go-librespot[19011]: time="2025-12-10T00:10:34-06:00" level=debug msg="app state loaded" Dec 10 00:10:34 volumio go-librespot[19011]: time="2025-12-10T00:10:34-06:00" level=debug msg="stored credentials not found" Dec 10 00:10:34 volumio go-librespot[19011]: time="2025-12-10T00:10:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 10 00:10:34 volumio sudo[19019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 00:09' Dec 10 00:10:34 volumio sudo[19019]: 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"