-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2025-12-20 11:56:02 UTC. -- Dec 20 11:55:00 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:00 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Dec 20 11:55:01 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:01 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:01 volumio go-librespot[1939]: go-librespot daemon starting... Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="app state loaded" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="stored credentials not found" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=info msg="zeroconf server listening on port 38749" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="obtained new client token: AAACdhgjKdBTD3YVGKBxbio3xHX6Q5ZXgh6b4o2aXgJzmH0wTRtAR+CryRGjbew748kZah2yxdyYKuoL3E8A4AZwkLls/vSR5QRd2hq3OXamSZvjJrkTKTdg2LiIh2xJ2gTIPm4ZrX0g90TnP9p9AtB3hfIGWKF/FElt3K4UdWe7Wdt3bUjtb09F5GFBdZSJpKPbLHmN7vi04RCMejUoPoblz2mSY1hW/9Vpw0k4iwtQmS5PYQ1bfY4feTo=" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="completed keyexchange" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" level=debug msg="completed challenge" Dec 20 11:55:01 volumio go-librespot[1939]: time="2025-12-20T11:55:01Z" 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 20 11:55:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:03 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:03 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Dec 20 11:55:04 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:04 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:04 volumio go-librespot[1948]: go-librespot daemon starting... Dec 20 11:55:04 volumio go-librespot[1948]: time="2025-12-20T11:55:04Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:04 volumio go-librespot[1948]: time="2025-12-20T11:55:04Z" level=debug msg="app state loaded" Dec 20 11:55:04 volumio go-librespot[1948]: time="2025-12-20T11:55:04Z" level=debug msg="stored credentials not found" Dec 20 11:55:04 volumio go-librespot[1948]: time="2025-12-20T11:55:04Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=info msg="zeroconf server listening on port 34279" Dec 20 11:55:05 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="obtained new client token: AAA14IcMdOMcQMp3aBYWSPjJ6zqWAGO0tJuLZ33Xaz7oDeEZiXaz71Vho+aEbD+dSXL/YbqkMsM4FmPcgIeGXqpKhdOeptXPA7fzIX4OoZZamYkP/wxVY1HkT2Q85f0y604/utrQ5u66MxfyyjnWQqwwPCRTwHPKGf0KHhlrSWVPsAiDfz4CdQbRitDI/HzCk8FPKdlvF9MaIypl2rlFtiaSFFfwrcJ4LSJ1Pl7z7Y2x7HeToqINvYvT" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="completed keyexchange" Dec 20 11:55:05 volumio go-librespot[1948]: time="2025-12-20T11:55:05Z" level=debug msg="completed challenge" Dec 20 11:55:06 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:06 volumio go-librespot[1948]: time="2025-12-20T11:55:06Z" level=debug msg="new websocket client" Dec 20 11:55:06 volumio volumio[904]: info: Connection to go-librespot Websocket established Dec 20 11:55:07 volumio go-librespot[1948]: time="2025-12-20T11:55:07Z" 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 20 11:55:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:07 volumio volumio[904]: info: Connection to go-librespot Websocket closed Dec 20 11:55:09 volumio volumio[904]: info: Getting Spotify volume Dec 20 11:55:09 volumio volumio[904]: (node:904) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:09 volumio volumio[904]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 20 11:55:09 volumio volumio[904]: (node:904) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Dec 20 11:55:09 volumio volumio[904]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Dec 20 11:55:09 volumio volumio[904]: info: CoreCommandRouter::volumioGetState Dec 20 11:55:09 volumio volumio[904]: info: CorePlayQueue::getTrack 0 Dec 20 11:55:09 volumio volumio[904]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 20 11:55:10 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:10 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Dec 20 11:55:10 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:10 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:10 volumio go-librespot[1956]: go-librespot daemon starting... Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="app state loaded" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="stored credentials not found" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=info msg="zeroconf server listening on port 43753" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="obtained new client token: AADwr4tgIKgWmxBguPrHQKu64cUIJP8bMbioEtVVEIBMd8sE3rUqPOTqUkIl7iHCrbltzAY815mVuWC88aciMh6Od6H2wOc9DpCrBgQetdC6OqMYEPGwTtuCovBeiPm11lyZBnO6UDJUY6I2vndMDOOeg0Sc0O81MEx70gEFVuQqWbv+pQmt6P26z6GNy/ShaYw/V6l/78nTXx20bCIqZQj4SjcfQyFvAOs3MWsiJETf4RY2G55KDvCEigw=" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="completed keyexchange" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" level=debug msg="completed challenge" Dec 20 11:55:10 volumio go-librespot[1956]: time="2025-12-20T11:55:10Z" 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 20 11:55:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:11 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:13 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:13 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Dec 20 11:55:13 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:13 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:13 volumio go-librespot[1964]: go-librespot daemon starting... Dec 20 11:55:13 volumio go-librespot[1964]: time="2025-12-20T11:55:13Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:13 volumio go-librespot[1964]: time="2025-12-20T11:55:13Z" level=debug msg="app state loaded" Dec 20 11:55:13 volumio go-librespot[1964]: time="2025-12-20T11:55:13Z" level=debug msg="stored credentials not found" Dec 20 11:55:13 volumio go-librespot[1964]: time="2025-12-20T11:55:13Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=info msg="zeroconf server listening on port 43113" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="obtained new client token: AAAuJ6pwu2ZdbcD5R254jgUBlTcT523fa/e9lTZSpx28a7eb8SWodH5afObjXeg6Efjko9c678zQAx9A+WuVnayPEf7A09BEJicC6PG01OnHYXsM7Iv1VTB2MDgzLoAYUHvhAJ5eHVq7wlx1niUpVDtv7aGJYbONkBykF09JHl9X+oc2OXF7k+rF7NVzYL5SKjJB4H4oODCoVYWtyzxtRPRq16PMhRuW3HclLmbNrY/lOVngavdGus81" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="completed keyexchange" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" level=debug msg="completed challenge" Dec 20 11:55:14 volumio go-librespot[1964]: time="2025-12-20T11:55:14Z" 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 20 11:55:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:16 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:16 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:17 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Dec 20 11:55:17 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:17 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:17 volumio go-librespot[1972]: go-librespot daemon starting... Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="app state loaded" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="stored credentials not found" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=info msg="zeroconf server listening on port 34241" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="obtained new client token: AADUTRdHGl3a0Q4gfye1ap72xU8In56Bb4B9RdVQJnetiesg4nG47TcxH5jZnmkn7lDnNOTRTNJLaKsRPiMwFnDt/q3amOf3SS0KfDrNX9ESd/Qyv3p0ikEoUUgcHZTBKnLzVJAjzXZmbju/Y56Dvkgo2UDnfHTF+BgW4712AQgdfMSDQL5xm9dxvJsH5l70MDy/w1hku7xRNGGRF1UeP+9/Wf4fnVmYl1E2pcBpaO73wM+MPyMIUy6vaNA=" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="completed keyexchange" Dec 20 11:55:17 volumio go-librespot[1972]: time="2025-12-20T11:55:17Z" level=debug msg="completed challenge" Dec 20 11:55:18 volumio go-librespot[1972]: time="2025-12-20T11:55:18Z" 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 20 11:55:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:19 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:19 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Dec 20 11:55:21 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:21 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:21 volumio go-librespot[1980]: go-librespot daemon starting... Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="app state loaded" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="stored credentials not found" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=info msg="zeroconf server listening on port 43861" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="obtained new client token: AACAu1FEEGxbb2llY8KlX+A/OmBScC+2DpwHDZxndRuLN6tZ9SAhGNVS9+zUqQUjkdT9V06FHbiqwx5qn60wezuWftYM2BDc0osxswsDj1inuCrhYA+uoHqagp+sDkhZaHGfO4SILAnm4OVtNXp+HlJ5LEITsjkyiQ821bih/Np6RTFLY7/ITN7g3N5vNk5kylTurYuFV08EQZiLfbt16xW8HK9CZhdaEoHZXSS4S7VgWuBKeNAtxwnVDtI=" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="completed keyexchange" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" level=debug msg="completed challenge" Dec 20 11:55:21 volumio go-librespot[1980]: time="2025-12-20T11:55:21Z" 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 20 11:55:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:22 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:22 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:23 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Dec 20 11:55:24 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:24 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:24 volumio go-librespot[1989]: go-librespot daemon starting... Dec 20 11:55:24 volumio go-librespot[1989]: time="2025-12-20T11:55:24Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:24 volumio go-librespot[1989]: time="2025-12-20T11:55:24Z" level=debug msg="app state loaded" Dec 20 11:55:24 volumio go-librespot[1989]: time="2025-12-20T11:55:24Z" level=debug msg="stored credentials not found" Dec 20 11:55:24 volumio go-librespot[1989]: time="2025-12-20T11:55:24Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=info msg="zeroconf server listening on port 41941" Dec 20 11:55:25 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:25 volumio volumio[904]: info: Connection to go-librespot Websocket established Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="new websocket client" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="obtained new client token: AAAomREzlW3LWbpshUNsR7JJ0HS0Z0QjB9ooAVima3Lp1OaRTwYAJf4DqLvsdl+/UgY9QIRqzxyytAq1v21hegtFUiOvejsKjq26H4GjPoKLEEFP3XD/YLFyfrLE/DciXz1BFQr6TIrZqQ40J+2kEb/ND6zneG73qvS2dFijT4IEtTNIUv1pkZq4ljsNq+l59Nb3IzCIHwm0YJuwYwMR6uPOAkpqifptPI6O3wGQN7hII6qsZop2MnG/" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="completed keyexchange" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" level=debug msg="completed challenge" Dec 20 11:55:25 volumio go-librespot[1989]: time="2025-12-20T11:55:25Z" 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 20 11:55:25 volumio volumio[904]: info: Connection to go-librespot Websocket closed Dec 20 11:55:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:28 volumio volumio[904]: info: Getting Spotify volume Dec 20 11:55:28 volumio volumio[904]: (node:904) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:28 volumio volumio[904]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 20 11:55:28 volumio volumio[904]: (node:904) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Dec 20 11:55:28 volumio volumio[904]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Dec 20 11:55:28 volumio volumio[904]: info: CoreCommandRouter::volumioGetState Dec 20 11:55:28 volumio volumio[904]: info: CorePlayQueue::getTrack 0 Dec 20 11:55:28 volumio volumio[904]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 20 11:55:28 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:28 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Dec 20 11:55:28 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:28 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:28 volumio go-librespot[1997]: go-librespot daemon starting... Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="app state loaded" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="stored credentials not found" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=info msg="zeroconf server listening on port 43263" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="obtained new client token: AADyFgO42QldFnNCrNAI+YPInSLKOYxbo7v7YuBEYqCRzQGxvJeJU/rL69/y2b20eqxPi/IKgIMdPK0B4XkZ1JXf/2UEkoEotb6NOOx9fgcEjpB8n9rgHXmHX3gLePQ9JHf0aTyjmtv0Hu915lu08Oplsl0XLl/hY8GkN8KxY1+e0hW6yX8SDExP5WzN4Qqw3L7+jAdgC5zWODMguBZU3mE5UEuUe+jQc4kYHlUJmddQeCWXNmfUN7KMi7g=" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="completed keyexchange" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" level=debug msg="completed challenge" Dec 20 11:55:28 volumio go-librespot[1997]: time="2025-12-20T11:55:28Z" 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 20 11:55:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:29 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:31 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:31 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Dec 20 11:55:31 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:31 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:31 volumio go-librespot[2006]: go-librespot daemon starting... Dec 20 11:55:31 volumio go-librespot[2006]: time="2025-12-20T11:55:31Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:31 volumio go-librespot[2006]: time="2025-12-20T11:55:31Z" level=debug msg="app state loaded" Dec 20 11:55:31 volumio go-librespot[2006]: time="2025-12-20T11:55:31Z" level=debug msg="stored credentials not found" Dec 20 11:55:31 volumio go-librespot[2006]: time="2025-12-20T11:55:31Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=info msg="zeroconf server listening on port 44965" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="obtained new client token: AABCDJZzgh9AdiQg/c4+5scHB9min8RJCMY6nouq/D+JGlu0GaASbk8sbGyvNd2kUgW8hR50B7FxPMuWMqeVhgOrU2+w7hgJKP6alvYfuc3Dkk76HPYkhlkpgXinlRlILMAM6dK9PUudh9Mczaood5cXvaAxV/DALMhkPuFzc78DvluaLjqqmcrMSoAeF/+Q8zWy0MsqBF1Au4GbqKIMjnTX9V8qCTKu5GUNOGVk6N7JqkpvcwlP4z0v" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="completed keyexchange" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" level=debug msg="completed challenge" Dec 20 11:55:32 volumio go-librespot[2006]: time="2025-12-20T11:55:32Z" 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 20 11:55:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:34 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:34 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:34 volumio volumio[904]: verbose: New Socket.io Connection to 192.168.68.101:3000 from 192.168.68.100 UA: Dart/3.9 (dart:io) Total Clients: 6 Dec 20 11:55:34 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 11:55:34 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 11:55:34 volumio volumio[904]: info: Discovery: Getting this device information Dec 20 11:55:34 volumio volumio[904]: info: CoreCommandRouter::volumioGetState Dec 20 11:55:34 volumio volumio[904]: info: CorePlayQueue::getTrack 0 Dec 20 11:55:34 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 11:55:35 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Dec 20 11:55:35 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:35 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:35 volumio go-librespot[2014]: go-librespot daemon starting... Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=debug msg="app state loaded" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=debug msg="stored credentials not found" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=info msg="zeroconf server listening on port 34347" Dec 20 11:55:35 volumio go-librespot[2014]: time="2025-12-20T11:55:35Z" level=debug msg="obtained new client token: AABHeMVqwDqUMjkyNAIbM1RiaMxlbjYBjpqlDA5SleZCN2rinC54QfNfNvb5shmEQ+jQf2kBGbs29txctMHyz97OTIH4YxIa/zDR9Tqgas6+iScMF+sFiuEynE7ikcgrIfC1a87OMiV7Y+/uB+hruYzRp4FncWIdWzInBAl/id1LAsVBE3qI3vcGAmBCuPrgrYSZ+OmxopHFZxWXpX5x9oGsVqRqbLgdDgkPudix9UW8U6ISJkjnt3MsVLI=" Dec 20 11:55:36 volumio go-librespot[2014]: time="2025-12-20T11:55:36Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:36 volumio go-librespot[2014]: time="2025-12-20T11:55:36Z" level=debug msg="completed keyexchange" Dec 20 11:55:36 volumio go-librespot[2014]: time="2025-12-20T11:55:36Z" level=debug msg="completed challenge" Dec 20 11:55:36 volumio go-librespot[2014]: time="2025-12-20T11:55:36Z" 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 20 11:55:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:37 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:37 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Dec 20 11:55:39 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:39 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:39 volumio go-librespot[2022]: go-librespot daemon starting... Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="app state loaded" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="stored credentials not found" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=info msg="zeroconf server listening on port 43225" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="obtained new client token: AAA0rg+tFEZABbYntaXn0tH1b6dL1OYxn1TDKTIrvMRH/sxe8yloQNfhh4CXB/H/aZQ7U9/L7PPvmy4kVhEgtfgWS/5vn2Go/3A35NRaKUJ9xaw6OVfeGrTKzg89XO0I+qWsIzLwGox3vmyDqAqjajBRXTaZ4KnmzVA8IKp5wlJaJRsuNqP9dure/viBFmkbLQPidxNavEEk92d/gIlh1jj2UCnLa3wf/tgD6azdPjniQLlgXI0oUu8R5hg=" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="completed keyexchange" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" level=debug msg="completed challenge" Dec 20 11:55:39 volumio go-librespot[2022]: time="2025-12-20T11:55:39Z" 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 20 11:55:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:40 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:40 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:41 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Dec 20 11:55:42 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:42 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:42 volumio go-librespot[2030]: go-librespot daemon starting... Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=debug msg="app state loaded" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=debug msg="stored credentials not found" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=info msg="zeroconf server listening on port 37029" Dec 20 11:55:42 volumio go-librespot[2030]: time="2025-12-20T11:55:42Z" level=debug msg="obtained new client token: AAAw7bUnmG8TtCShqQKGED6D1FNw85Ol4ha52fWOWCQ1W1pkmN+i6bOgQUQIZxiK15GeZFGHS5t53ZCM0QvuVdjf61Z8TMpUR/4uLQ8s56i52vJfRgjQoG7Vn2RlRfEZBTTXVYVLZQ+0lwgW9j5vK18/auDDHKJgWaxJjfOtcOMZc72AcnB8aB80MRwp7tPpAFLpfoKVF5AbQjV9dCZpb1pJKtDg1AbjbbnyHFF1VVweQhJ8oMxrxug3Vcc=" Dec 20 11:55:43 volumio go-librespot[2030]: time="2025-12-20T11:55:43Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:43 volumio go-librespot[2030]: time="2025-12-20T11:55:43Z" level=debug msg="completed keyexchange" Dec 20 11:55:43 volumio go-librespot[2030]: time="2025-12-20T11:55:43Z" level=debug msg="completed challenge" Dec 20 11:55:43 volumio go-librespot[2030]: time="2025-12-20T11:55:43Z" 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 20 11:55:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:43 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:43 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Dec 20 11:55:46 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:46 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:46 volumio go-librespot[2039]: go-librespot daemon starting... Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="app state loaded" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="stored credentials not found" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:46 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="new websocket client" Dec 20 11:55:46 volumio volumio[904]: info: Connection to go-librespot Websocket established Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=info msg="zeroconf server listening on port 45279" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="obtained new client token: AACt1eijQ3t2XSPuSgXh5+56JQEVTQDbNcOlMxeUQKNF9r2C7nT9RSnqsDaCTv9fmXY5visfpaN3rth8Ke6M9AcjGUhHCGHGQO5urzvEbac/Qbfca+Z5WBjS+MMuyxoI/UTLzD/crtpofe5OQaP31DS4ZbIJuR3/rmptox+WxVutvFeGrMdD4/26oO8RYg9jJaL4G6W4BbBvdr7XoqIW3AFK1SP/cSWalFnUJTav1yK7+/etxbJ62X+CmTo=" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="completed keyexchange" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" level=debug msg="completed challenge" Dec 20 11:55:46 volumio go-librespot[2039]: time="2025-12-20T11:55:46Z" 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 20 11:55:46 volumio volumio[904]: info: Connection to go-librespot Websocket closed Dec 20 11:55:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:47 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:49 volumio volumio[904]: info: Getting Spotify volume Dec 20 11:55:49 volumio volumio[904]: (node:904) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:49 volumio volumio[904]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Dec 20 11:55:49 volumio volumio[904]: (node:904) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 10) Dec 20 11:55:49 volumio volumio[904]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Dec 20 11:55:49 volumio volumio[904]: info: CoreCommandRouter::volumioGetState Dec 20 11:55:49 volumio volumio[904]: info: CorePlayQueue::getTrack 0 Dec 20 11:55:49 volumio volumio[904]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 20 11:55:49 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:49 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Dec 20 11:55:49 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:49 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:49 volumio go-librespot[2047]: go-librespot daemon starting... Dec 20 11:55:49 volumio go-librespot[2047]: time="2025-12-20T11:55:49Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:49 volumio go-librespot[2047]: time="2025-12-20T11:55:49Z" level=debug msg="app state loaded" Dec 20 11:55:49 volumio go-librespot[2047]: time="2025-12-20T11:55:49Z" level=debug msg="stored credentials not found" Dec 20 11:55:49 volumio go-librespot[2047]: time="2025-12-20T11:55:49Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=info msg="zeroconf server listening on port 39021" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="obtained new client token: AABK63B6BMmxxoqRHDdKECA8fqpfMGGZiMq9Y4K8EORmeXWzLYJylC1XhYhaYfX99vkD2kHh2wJdb22O9feCmVOLkJw47rpRQ6w+2oB9Yc/sRbutWfD+nhu4PwCs4uvciY1cEsW8dYftMcCb5Xc3fR1JnwchEaEXVNC9B+wLZqdgY0RAhsPmftMrUnpPd7kTUDHfo2QNk8U8DUtYNrGg0Ki4fyt4GTBIyPpDErO2NNxcRr6Ws9ZfFYMo" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="completed keyexchange" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" level=debug msg="completed challenge" Dec 20 11:55:50 volumio go-librespot[2047]: time="2025-12-20T11:55:50Z" 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 20 11:55:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:52 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:52 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:53 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:55:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Dec 20 11:55:53 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:53 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:53 volumio go-librespot[2055]: go-librespot daemon starting... Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="app state loaded" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="stored credentials not found" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=info msg="zeroconf server listening on port 33501" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="obtained new client token: AABYOtflBARbCq3cyMuNXKxK9dYe3dOnn+zCWxRJNTrCXYV1DmpfE7Hzls95afbnTAZfcAXcJTBrr1WIHebfD/JalLPLlUo2kgN38EPUj7malVdNWHtnmhcDmXeJM6a0sl/fhonHopzS84lefx73GQQuFq1+o9219nsUNrzWgnavpAYU3ry6BO42uecY1umKlV+KNuy2XiPUxdmZGZL4sgPjcy7rE9ubnrL7G48HkS4IjgLl2QNDhbAAn+A=" Dec 20 11:55:53 volumio go-librespot[2055]: time="2025-12-20T11:55:53Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:54 volumio go-librespot[2055]: time="2025-12-20T11:55:54Z" level=debug msg="completed keyexchange" Dec 20 11:55:54 volumio go-librespot[2055]: time="2025-12-20T11:55:54Z" level=debug msg="completed challenge" Dec 20 11:55:54 volumio go-librespot[2055]: time="2025-12-20T11:55:54Z" 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 20 11:55:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:55 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:55 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:55:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Dec 20 11:55:57 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:55:57 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:55:57 volumio go-librespot[2063]: go-librespot daemon starting... Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="app state loaded" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="stored credentials not found" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=info msg="zeroconf server listening on port 36673" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="obtained new client token: AACHNXYwKsizOgjCMAbBcQSFVhpBUldT2UdqFzF+Bs5P2CB4HTFxy7QzwTmxui4VXXLrY0xNjZDWTnn/6TyZHMDllEb7U3PCSxnpE2RDZ1UFK16tsPIJf87fZykbRL5ci3LGu9M4o0NLrRPGq/KsEbkdOvf+1cOQfpTjtrSK9ubTjsg4UAcq4NSKaoNnokvFUE2ywHUNgYFtGCA6IPtMd7X7JvF5LVPZ922xkkvgue/6UK8xX7oQksyKezE=" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="completed keyexchange" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" level=debug msg="completed challenge" Dec 20 11:55:57 volumio go-librespot[2063]: time="2025-12-20T11:55:57Z" 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 20 11:55:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:55:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:55:58 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:55:58 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:55:59 volumio wpa_supplicant[808]: RRM: Ignoring radio measurement request: Not RRM network Dec 20 11:56:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 11:56:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Dec 20 11:56:00 volumio systemd[1]: Stopped go-librespot Daemon. Dec 20 11:56:00 volumio systemd[1]: Started go-librespot Daemon. Dec 20 11:56:00 volumio go-librespot[2071]: go-librespot daemon starting... Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=info msg="running go-librespot 0.4.0" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="app state loaded" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="stored credentials not found" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=info msg="zeroconf server listening on port 36903" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="obtained new client token: AADpZy2F3dKvBBDu7wJz0e8pc0QNXphBugNIsZ5hDQnT4QaXnGgPHEh+PulE6j3rRLKNw6D4uJ16dGwQlh8ZuUC9n+i/buqMYa7BK42o/zzdeWkOsM1t+JaCgI+NwlwC5LxUemTNJxHRV3aJDPtETXwU+JSolmfClySNbA40St/DQK1IuJ7IEBmmNedyWaZ42768QXFulNGxm7UKrPWFPovA5L7sf4Lwtfyuq10sqkaRs9EUUWaYEVV0n7U=" Dec 20 11:56:00 volumio go-librespot[2071]: time="2025-12-20T11:56:00Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 20 11:56:01 volumio go-librespot[2071]: time="2025-12-20T11:56:01Z" level=debug msg="completed keyexchange" Dec 20 11:56:01 volumio go-librespot[2071]: time="2025-12-20T11:56:01Z" level=debug msg="completed challenge" Dec 20 11:56:01 volumio go-librespot[2071]: time="2025-12-20T11:56:01Z" 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 20 11:56:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 11:56:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 11:56:01 volumio volumio[904]: info: Initializing connection to go-librespot Websocket Dec 20 11:56:01 volumio volumio[904]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 11:56:02 volumio volumio[904]: Searching all installed plugins Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: , search Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 20 11:56:02 volumio volumio[904]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 20 11:56:02 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Dec 20 11:56:02 volumio volumio[904]: info: [1766231762323] ControllerCalmRadio::searchCategories Dec 20 11:56:02 volumio volumio[904]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 11:56:02 volumio volumio[904]: TypeError: Cannot read property 'cats' of undefined Dec 20 11:56:02 volumio volumio[904]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Dec 20 11:56:02 volumio volumio[904]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Dec 20 11:56:02 volumio volumio[904]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1057:32) Dec 20 11:56:02 volumio volumio[904]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:655:44) Dec 20 11:56:02 volumio volumio[904]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:451:27) Dec 20 11:56:02 volumio volumio[904]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:413:44) Dec 20 11:56:02 volumio volumio[904]: at Socket.emit (events.js:315:20) Dec 20 11:56:02 volumio volumio[904]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 20 11:56:02 volumio volumio[904]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 20 11:56:02 volumio volumio[904]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 11:56:02 volumio sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 11:55 Dec 20 11:56:02 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"