-- Logs begin at Wed 2026-02-18 17:19:13 +07, end at Wed 2026-02-18 19:53:57 +07. -- Feb 18 19:52:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631051. Feb 18 19:52:00 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:00 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:00 volumio go-librespot[3428]: go-librespot daemon starting... Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=debug msg="app state loaded" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=info msg="zeroconf server listening on port 40979" Feb 18 19:52:00 volumio go-librespot[3428]: time="2026-02-18T19:52:00+07:00" level=debug msg="obtained new client token: AADvNc0pIj35b/QaXEcTOFQhxlcTwU2+Yp011rrZ1pzKvnQSVA0xev3m0DmuOSjeU/aCogwLOZ9Ue00QWEwqU4Cp5wh4XScUPG7gjJrcbCwR2Ev3xakGyyXCU3HjsvTX+oZ6PK7xVKupcW0nfDIiZXhWRJcRlo13xvWTNji4/QHeH4ZHRgjWgnz7veM1b769YWZiBBBCEvXkLX2TbWrigLIERppzM1mFgzAxjSo05gjNDWIUt5b3P+mQ" Feb 18 19:52:01 volumio go-librespot[3428]: time="2026-02-18T19:52:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:01 volumio go-librespot[3428]: time="2026-02-18T19:52:01+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:01 volumio go-librespot[3428]: time="2026-02-18T19:52:01+07:00" level=debug msg="completed challenge" Feb 18 19:52:01 volumio go-librespot[3428]: time="2026-02-18T19:52:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:02 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:02 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631052. Feb 18 19:52:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:04 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:04 volumio go-librespot[3450]: go-librespot daemon starting... Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="app state loaded" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=info msg="zeroconf server listening on port 44175" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="obtained new client token: AAAE5SpQieYi0eKodvyOadyd5zwHOScLvmsvJB+BbZ1iosBCJq8+y5+5Zy//47FvPfuo0JFLKz8CQ1qwBekidYeyg50meOLuDNxF1m2iQfsL7yHop2syRhtHDOwPosKQ62A9ErDK/4BKeK8R1C1eEEMTjQSgHgULc5zENzL3Tu45DkBikDElbmCXFrR5nd/53ZwP+FoiGhY+Nmg00SAc14JoCr4YrLytd0uaYyqyVraNOtB+DeCmkl19" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=debug msg="completed challenge" Feb 18 19:52:04 volumio go-librespot[3450]: time="2026-02-18T19:52:04+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:05 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:05 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631053. Feb 18 19:52:08 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:08 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:08 volumio go-librespot[3461]: go-librespot daemon starting... Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="app state loaded" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=info msg="zeroconf server listening on port 34185" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="obtained new client token: AAA0CiD+8YF/jdC8GHKCtot8ao2BAPBjjjPkTUpC1Jw10YI2BFaUtinjy+eUG1kX3TmoncukykzE088MNqaKJJfED2X+EwLMCx6UZRC7FwA6XUbVNGOLZI8GHdVt4EmPG60TuFVQFElL0SLPTTXLMEPwdx59C2L/RQXLZK79msbx7+RDV1oQQ8S3ZJVIelBauxTLawJhHIoHkzQwkUtpjEIpMgimYxphPdMTKuDqvolLV/Y5XCn0nXvS" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=debug msg="completed challenge" Feb 18 19:52:08 volumio go-librespot[3461]: time="2026-02-18T19:52:08+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:08 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:08 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:11 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:11 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631054. Feb 18 19:52:11 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:11 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:11 volumio go-librespot[3473]: go-librespot daemon starting... Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=debug msg="app state loaded" Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:11 volumio go-librespot[3473]: time="2026-02-18T19:52:11+07:00" level=info msg="zeroconf server listening on port 35885" Feb 18 19:52:12 volumio go-librespot[3473]: time="2026-02-18T19:52:12+07:00" level=debug msg="obtained new client token: AAA1+iDxWx5+PpQJIbQgB4RXwcCvwH5+srDVqCUVw2uyl5gFSZ+Wt90qYNr4M0TGXfLs76mTdXMeGlKbi2S9GvjuWT5H6a5GMTpMHZMYDYvck5+WCBNXndCNV4gt5lwYbOsxnNzAnWjTVCzGWpe5fwHu9FWMMmJ7lkIcQguHEWWZeLl8YVM5l4xP94PDDgb6j+Wjf25c/zX9e2ru3g8RSmuIqZxLo/nOSlLl7kF0jGimfkhY2C7Bvg==" Feb 18 19:52:12 volumio go-librespot[3473]: time="2026-02-18T19:52:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:12 volumio go-librespot[3473]: time="2026-02-18T19:52:12+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:12 volumio go-librespot[3473]: time="2026-02-18T19:52:12+07:00" level=debug msg="completed challenge" Feb 18 19:52:12 volumio go-librespot[3473]: time="2026-02-18T19:52:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:14 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:14 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631055. Feb 18 19:52:15 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:15 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:15 volumio go-librespot[3494]: go-librespot daemon starting... Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=debug msg="app state loaded" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=info msg="zeroconf server listening on port 33381" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=debug msg="obtained new client token: AAA5v6FGlv7r9I+5AGl+5aN56nYn0j2uFjjCc7ScrsZzb0MXlD352KW3Ahl+oSgq5K0MLZIOwoyZTI5xavwzjziVizZDSPNlEbFA1lfJ/RymPzHWzv+34SmKxxqusht68WTjIudr3RAPYEtrA/wKur1cC5Gp3frT189Nd4qhepjK7XKXc5RpRlktvnegD89g1qYXZjDKjkAaviVkY2RK+vbLJ8ZmlV5tWTeqe0Cdl8HJ6ncjVMmHHLZG" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:15 volumio go-librespot[3494]: time="2026-02-18T19:52:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 18 19:52:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:17 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:17 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631056. Feb 18 19:52:18 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:18 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:18 volumio go-librespot[3502]: go-librespot daemon starting... Feb 18 19:52:18 volumio go-librespot[3502]: time="2026-02-18T19:52:18+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:18 volumio go-librespot[3502]: time="2026-02-18T19:52:18+07:00" level=debug msg="app state loaded" Feb 18 19:52:18 volumio go-librespot[3502]: time="2026-02-18T19:52:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=info msg="zeroconf server listening on port 37523" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="obtained new client token: AAD4dX+4htgafAuDLIFyblohy7ltecV6u7YHGBpngMAqWYJeHTAF/BCcQ1UGfj0o//B+nEQGYKjexjeuSCIMh+qpM1+Uv4ISO/vmQhaoELcGXUkLJM1Uvg/mqDw3ss4vsWpT6qBBRaHRX7Oe5x3leFKCll9JWJzJGFKXyGQ/nlMs0pMtJp7vH/7QzBPQSaHN3cuKiBOHjtPoJ/7l/ITUvoeXytQFmk8rgyhdVm35vDdfAXYyYnYjRQ==" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=debug msg="completed challenge" Feb 18 19:52:19 volumio go-librespot[3502]: time="2026-02-18T19:52:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:20 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:20 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631057. Feb 18 19:52:22 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:22 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:22 volumio go-librespot[3553]: go-librespot daemon starting... Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=debug msg="app state loaded" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=info msg="zeroconf server listening on port 34687" Feb 18 19:52:22 volumio go-librespot[3553]: time="2026-02-18T19:52:22+07:00" level=debug msg="obtained new client token: AAABm3Fr+632/IDYN6Bqf1neqMCRdbYoqxuy1zngCRomFJOphINlf1SQN7Tsz9tIUgL1RbKXP4WD9WdTuDItTVqdU8tKJd+UZQme2lshZ7oa+BiFa/xHQ4pXzvbFpS+XHw38U3K20XoYPAgIhw+B5JsxvQNxtVz07wMZlsnUJa1LVBhTBEynqjk8phWo5rmINTZ/LotAwfmdm1es01rYaGGdMHn7BxXx+kHM3gCM6jRUlevBuRmDOR/Q" Feb 18 19:52:23 volumio go-librespot[3553]: time="2026-02-18T19:52:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:23 volumio go-librespot[3553]: time="2026-02-18T19:52:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 18 19:52:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:23 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:23 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631058. Feb 18 19:52:26 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:26 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:26 volumio go-librespot[3577]: go-librespot daemon starting... Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="app state loaded" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=info msg="zeroconf server listening on port 35281" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="obtained new client token: AAC/Vs9lYzJl5AOjQzkdkIvghT5zZh1DXkw2BzzxopHDBx7r0cbfr/8Sj6Hb/81gBW1uytYozqoq1QjAC/WfGPeESsquT+6i+60YTUq+rFm/T9LMEZQVIVApJX4YoSH7GTu8xeNAZ3bFhEMvSUy61KBGfTBAXLtfSAisFIbXIihCFCHWY1N9/yvR+XItBsGPMVXymmsfJhyHdJUu2qvRr7C95qu+0KDzyO9PtxSsT4vqq4MrH+bV1cyU" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="completed challenge" Feb 18 19:52:26 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=debug msg="new websocket client" Feb 18 19:52:26 volumio volumio[1068]: info: Connection to go-librespot Websocket established Feb 18 19:52:26 volumio go-librespot[3577]: time="2026-02-18T19:52:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:26 volumio volumio[1068]: info: Connection to go-librespot Websocket closed Feb 18 19:52:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:29 volumio volumio[1068]: info: Getting Spotify volume Feb 18 19:52:29 volumio volumio[1068]: (node:1068) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:29 volumio volumio[1068]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 18 19:52:29 volumio volumio[1068]: (node:1068) 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: 117615) Feb 18 19:52:29 volumio volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 18 19:52:29 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:52:29 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:52:29 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 19:52:29 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:29 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631059. Feb 18 19:52:30 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:30 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:30 volumio go-librespot[3586]: go-librespot daemon starting... Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=debug msg="app state loaded" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=info msg="zeroconf server listening on port 33985" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=debug msg="obtained new client token: AAAwEhjdLkM/tlAZpBTde6Sa65HLDmGWmuT4Qv9ORIAx6BiRJNmNNS1OooaFOnVw0bfgfE7C9nugRbJPTxerVWlDCd/RFcrxYIMdQexMHhFK5NB0q+6/YXk1OMQmhkXyiTzQ3QRyXl107mvtmDF1Yqunu1ZuB39Ky4Mc9p1IiUd6DN3MCqRnBqtk+0NPrjRDR3uCVSbDnh/pZeaI8e/0cZCUuh4b4XC06u3HQqX2mJbQcsyZVp/pdtbV" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:30 volumio go-librespot[3586]: time="2026-02-18T19:52:30+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.29:54670->104.199.241.202:4070: read: connection reset by peer" Feb 18 19:52:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:32 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:32 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631060. Feb 18 19:52:33 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:33 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:33 volumio go-librespot[3594]: go-librespot daemon starting... Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=debug msg="app state loaded" Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:33 volumio go-librespot[3594]: time="2026-02-18T19:52:33+07:00" level=info msg="zeroconf server listening on port 34309" Feb 18 19:52:34 volumio go-librespot[3594]: time="2026-02-18T19:52:34+07:00" level=debug msg="obtained new client token: AABWqjLEtVIx2gf22jnA/OyEiApd2mMiz34ZAoHrTITnglv5Iw8JpjXy5QXqDa0lltvS3R1PM+fu62wUboq5lnOCyMPTvmaDeLp09iBV4MEcdZ5bw92AYtOCoR6ETsyS3G6I/8dCghJ2gob7fn7zHUULUJp/untzOtRpB5ifGEO+Z550xB1gSkAkbRtB94/B86Qo4JdG/pRhu4qBMOQf0GgswXcPBuTOU25csBgaMpJuQrin0ak0oA==" Feb 18 19:52:34 volumio go-librespot[3594]: time="2026-02-18T19:52:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:34 volumio go-librespot[3594]: time="2026-02-18T19:52:34+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:34 volumio go-librespot[3594]: time="2026-02-18T19:52:34+07:00" level=debug msg="completed challenge" Feb 18 19:52:34 volumio go-librespot[3594]: time="2026-02-18T19:52:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:35 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:35 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631061. Feb 18 19:52:37 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:37 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:37 volumio go-librespot[3615]: go-librespot daemon starting... Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="app state loaded" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=info msg="zeroconf server listening on port 39347" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="obtained new client token: AADf6prohrGOUCDb92VyKteSlLSUYpT/NNFfhx7XWLtPgKksYS1KI8lVcvOWyw3clBNXXfb+byDEyaIur9vQEOzjNt6f1id1R54pDuJZnbgbyL9Mpr2vWqO8oojAx1JDCGpjFZRcW/V5voGbDlo1I3mAn/2fdEsly97I274eLCbl3TUbIx72epXFK+57CAqP52EvQV0nEQn7qYTXgpMxHqOpuy0B4E1d79VHfg1YfvbvkZNRKi8VDW2F" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=debug msg="completed challenge" Feb 18 19:52:37 volumio go-librespot[3615]: time="2026-02-18T19:52:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:38 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:38 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631062. Feb 18 19:52:41 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:41 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:41 volumio go-librespot[3622]: go-librespot daemon starting... Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="app state loaded" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=info msg="zeroconf server listening on port 39513" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="obtained new client token: AABZ6fd2Ka0EAYaYHobXwqHwXIEyBvR2gXh1zUmThteXa8Ga/GF4wrPnHxF4Uxll/eM9oEG69qfpACkN+g9Oeb8HnM3y/mSw55ZTj3jCKwpYPI81RCPpfyXt233KorD7VQtqCalpOHSnLmX29QcJxzZMQM7NJiCwXORVLD/YjfG/saHvT1QhsvN1uLLjSXhUHBS8ok2Pb9AA9FomKTBpumVLav8O/5AyPhiBGPTW0sjGfBfmgMzMWPcW" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=debug msg="completed challenge" Feb 18 19:52:41 volumio go-librespot[3622]: time="2026-02-18T19:52:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:41 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:41 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631063. Feb 18 19:52:44 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:44 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:44 volumio go-librespot[3643]: go-librespot daemon starting... Feb 18 19:52:44 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:44 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:44 volumio go-librespot[3643]: time="2026-02-18T19:52:44+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:44 volumio go-librespot[3643]: time="2026-02-18T19:52:44+07:00" level=debug msg="app state loaded" Feb 18 19:52:44 volumio go-librespot[3643]: time="2026-02-18T19:52:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=info msg="zeroconf server listening on port 41455" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="obtained new client token: AACu8m3P7k+sjofxMmTwUgn1/Vvp63eDnsJVp4uuX4+cvq8yqoTd1YJ/NQVuaw5vraav3wpqnGCv2XpOunsoX+/RNxWm5sIarfE7WUEOj1XpQ4KnOnVsTY5LR6OkoMZgMlAAWZtkfMY9N1Wg4JvECRZA4QZGg54tMjQD3j9XWufhRFJLBl1PJtJhVzhTxtb2jKJ0axNEdTH89guOAirm0sQ05jXn6HsrbhMoIggNLJT3JSFxpfgPfA==" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=debug msg="completed challenge" Feb 18 19:52:45 volumio go-librespot[3643]: time="2026-02-18T19:52:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:47 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:47 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631064. Feb 18 19:52:48 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:48 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:48 volumio go-librespot[3655]: go-librespot daemon starting... Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="app state loaded" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=info msg="zeroconf server listening on port 42691" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="obtained new client token: AABZPvk0ytrbfQaO6h6Gli0la4CvFOx39EUQrdFAJ50lHB1zfO55H9DetGXfc0QUZR8XsIGPfQBsnHKx0J7DTd2qJLTbIF3ONhvVDjtvufhmYtOZqhzb6+bHUggXgnSWEJ2goC9s/Wn7hmo4xHNukWstcayRGt4hhLzOafIWqYr4aqXgXB7uO7S2QKZeNAtdyOFGNwfbDSEQc7+awxj8rTaTR5hiJ/sKnhdH2GareRmIAOaiiS6XTpSk" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:48 volumio go-librespot[3655]: time="2026-02-18T19:52:48+07:00" level=debug msg="completed challenge" Feb 18 19:52:49 volumio go-librespot[3655]: time="2026-02-18T19:52:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:50 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:50 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631065. Feb 18 19:52:52 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:52 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:52 volumio go-librespot[3662]: go-librespot daemon starting... Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="app state loaded" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=info msg="zeroconf server listening on port 38871" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="obtained new client token: AACOCshALwdBzbVHOXNnw3FK5XkkNl3iPNuBRjrFZv06ADfKGDqDz67smsQGmNSYWKbR1ut34V1TFi+YqFh7JajDZDKCGEL7fJ2cq4H3bu4Lla4R3BPLDrrpb8y6Hi5eCdhZvOSH7LaYlqmwZY6P2LwHhbeXikbIu86Qdahe4G24k0CFLXdXvcCMrtj4smaODxWCvcyjDFijdf08O6Fi3DzMRGratDiTLsEk4PPePQbcgMZ5b3QaM0ib" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="completed keyexchange" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=debug msg="completed challenge" Feb 18 19:52:52 volumio go-librespot[3662]: time="2026-02-18T19:52:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:52:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:53 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:53 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631066. Feb 18 19:52:55 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:55 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:55 volumio go-librespot[3683]: go-librespot daemon starting... Feb 18 19:52:55 volumio go-librespot[3683]: time="2026-02-18T19:52:55+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:55 volumio go-librespot[3683]: time="2026-02-18T19:52:55+07:00" level=debug msg="app state loaded" Feb 18 19:52:55 volumio go-librespot[3683]: time="2026-02-18T19:52:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=info msg="zeroconf server listening on port 41499" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=debug msg="obtained new client token: AAC9tWttE9lmoc6EgbV4jE4MHEtmJO4uu0dQkYUYP8pn8xJ7edsC0fKeTPpr83xpTj+uj5cmj0CXDXr7K01mt69iD7P3ewk0J9mgGbDVShM+Y/RwnO7c+WmIpvk6Y8orkdd0GGof8+vzFI/aBmHsgB+77kttW/zLJ9YYV5GsKAuMA8wLcLW+eBarkVyxQAxrA6j89ZbqOISWaU66M8xGMEI/M1yHpKebDxyjybHN24YbvW2XwwlT+g==" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:52:56 volumio go-librespot[3683]: time="2026-02-18T19:52:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.29:43836->104.199.241.202:4070: read: connection reset by peer" Feb 18 19:52:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:52:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:52:56 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:56 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:52:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:52:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631067. Feb 18 19:52:59 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:52:59 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:52:59 volumio go-librespot[3690]: go-librespot daemon starting... Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=debug msg="app state loaded" Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=info msg="zeroconf server listening on port 39773" Feb 18 19:52:59 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=debug msg="new websocket client" Feb 18 19:52:59 volumio volumio[1068]: info: Connection to go-librespot Websocket established Feb 18 19:52:59 volumio go-librespot[3690]: time="2026-02-18T19:52:59+07:00" level=debug msg="obtained new client token: AABMRrU2myvosEFxA4j/z1xftOj3yHNhf5XWPq0gwVe2N2TzjmzN+o0yvq/z61Uu2kBd9buwC7FAKb9ObrP+/7IRf8Fpc3dIGJoERPQJTd2eJl/2QRUSVnqWpGwsBMgB/UbqrLhYwbD7AleiqGeCIP7tK0Wub1jp4UKyrWcFVQmqM0GC4dHUQsuwOV27+AOT4Uz4c3FZZ8sBehkGG9cVE9U8N3rdrQaTxy3pw+cC7O3vwfCpvewoxe0v" Feb 18 19:53:00 volumio go-librespot[3690]: time="2026-02-18T19:53:00+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 19:53:00 volumio go-librespot[3690]: time="2026-02-18T19:53:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 19:53:00 volumio go-librespot[3690]: time="2026-02-18T19:53:00+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:00 volumio go-librespot[3690]: time="2026-02-18T19:53:00+07:00" level=debug msg="completed challenge" Feb 18 19:53:00 volumio go-librespot[3690]: time="2026-02-18T19:53:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:00 volumio volumio[1068]: info: Connection to go-librespot Websocket closed Feb 18 19:53:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:02 volumio volumio[1068]: info: Getting Spotify volume Feb 18 19:53:02 volumio volumio[1068]: (node:1068) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:02 volumio volumio[1068]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 18 19:53:02 volumio volumio[1068]: (node:1068) 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: 117616) Feb 18 19:53:02 volumio volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 18 19:53:02 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:02 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:02 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 19:53:03 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:03 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631068. Feb 18 19:53:03 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:03 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:03 volumio go-librespot[3697]: go-librespot daemon starting... Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="app state loaded" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=info msg="zeroconf server listening on port 46685" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="obtained new client token: AABAUphxLYsxYtU/4ec9dId5s6UKTpizBh14SC0lAoJQV/3iozmvY5CFzwJPy4JJatkFak/Vxm6D+qUwn3202s1uPmXHr6N2xnyZgb5Dky/d3bQgUBCFPXphpQ6+Y26q+e1n3uf/EmvBPZOkzKuRlUzJBAcXRy7qDqg7ZnqHd1Tjm56m9QFv0iy+exUa5s2Jhc/dTrULxnMdgSZMxPw4oJSkTq0RfKwc/3zDdt+Q64eR9cSTF4+WHpQD" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=debug msg="completed challenge" Feb 18 19:53:03 volumio go-librespot[3697]: time="2026-02-18T19:53:03+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:06 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:06 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631069. Feb 18 19:53:07 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:07 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:07 volumio go-librespot[3721]: go-librespot daemon starting... Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="app state loaded" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=info msg="zeroconf server listening on port 34657" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="obtained new client token: AABJWzviJjZOXqUpHFyT1AwShxRhtC1fjO9sGvmH72UYkoa5orJhBjHKeqORseyPFBjciDPxaSwqLboiKP64uWfDKjOlCOf3ThsGlDjzpyMRQFy9is1ux/pIuWXLEOhRD2sZ4ASh14Xd063ujMv9mCIBczBjvv8ejmisfsvxq8pSsjA9pg8TZUIPxMlK099vAYdcPWvnF0JTaDg0OApVN6F4/efg6AbAcyrVDXQiDwjVA2RE0TVt5uHw" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=debug msg="completed challenge" Feb 18 19:53:07 volumio go-librespot[3721]: time="2026-02-18T19:53:07+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:09 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:09 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631070. Feb 18 19:53:10 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:10 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:10 volumio go-librespot[3729]: go-librespot daemon starting... Feb 18 19:53:10 volumio go-librespot[3729]: time="2026-02-18T19:53:10+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:10 volumio go-librespot[3729]: time="2026-02-18T19:53:10+07:00" level=debug msg="app state loaded" Feb 18 19:53:10 volumio go-librespot[3729]: time="2026-02-18T19:53:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=info msg="zeroconf server listening on port 45879" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="obtained new client token: AABxuQ1W36yB768V0ik4oiq1EQrQy/hIw8s/fVOgwOV+hkabG+hRWAyMT2CWNAo7mLuF48YoVhhtRJ42KD36blqSjcvLX+Fv6NGpPaUecNLL7I7435d+pYTGEPvZrI9ddGE+NXzbEosGN/KI+HHWNopYZCk1EyxfJ7vkNrQs9q5z4iZ98kY7boxBb+dwvY3j6fPm/H3ADvGexQ4tEQouLDtxBY218QhEkQVoZR5PtgBRAR8fn3yUew==" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=debug msg="completed challenge" Feb 18 19:53:11 volumio go-librespot[3729]: time="2026-02-18T19:53:11+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:12 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:12 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631071. Feb 18 19:53:14 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:14 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:14 volumio go-librespot[3758]: go-librespot daemon starting... Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=debug msg="app state loaded" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=info msg="zeroconf server listening on port 33713" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=debug msg="obtained new client token: AABownhg0F4+AAfWgPbPy/9cGrR2XycndvA46JwNvSRUXhNeLzSorlc2hNUDifP1zMgN1RVDnlwbrn1UNQzF11evOYcS4RJRNXexmjPEUYBYWUMIY7iLLWnTlyQ0SvXhoCoe4o8IbykH9lMjA1cQei8UrebJjgnRyz84DX00pKxum0FHK/qHP6Bzu8nVtH8Vam8AfnFGFYPPDysXjqMUUc0XlhhF3ikzbzckdBy1qVFFQ27LHM2O+ZII" Feb 18 19:53:14 volumio go-librespot[3758]: time="2026-02-18T19:53:14+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 19:53:15 volumio go-librespot[3758]: time="2026-02-18T19:53:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 19:53:15 volumio go-librespot[3758]: time="2026-02-18T19:53:15+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:15 volumio go-librespot[3758]: time="2026-02-18T19:53:15+07:00" level=debug msg="completed challenge" Feb 18 19:53:15 volumio go-librespot[3758]: time="2026-02-18T19:53:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:15 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:15 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:18 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.14 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 18 19:53:18 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:18 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:18 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:18 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:18 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:18 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:18 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:18 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631072. Feb 18 19:53:18 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:18 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:18 volumio go-librespot[3765]: go-librespot daemon starting... Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="app state loaded" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=info msg="zeroconf server listening on port 37991" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="obtained new client token: AADEBY2lUKY1f/Rs09undQX0yMqAU3pMlKhePHxX6YW2BamPy6+nUYaMvcRXl6ED2H1D+AnUc5B8G/FNoTr3pMU+4rYdohGmA/l3JCtR03DzKxHOnRwFsVI+OpRpMiO3qqDmYJXf7kjHQuKqtAwPFzQc7Udr1nxxVi/qlMOtDLY9n+8BEjhiZ9Ck962xxgQT9uhe9JEHaEC9079PwaUzIFDsbiqkX8YYyUjMvt4qo/7qCt9Gw13ZKLj4" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=debug msg="completed challenge" Feb 18 19:53:18 volumio go-librespot[3765]: time="2026-02-18T19:53:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:19 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:19 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:19 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.14 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:19 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:19 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:19 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.14 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 19:53:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 19:53:21 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:21 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631073. Feb 18 19:53:22 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:22 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:22 volumio go-librespot[3817]: go-librespot daemon starting... Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="app state loaded" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=info msg="zeroconf server listening on port 35975" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="obtained new client token: AAA6nEcKDfqXQrkYHeLYpib8nS92FlEwzizZ8+hUtwDlVqjFo3TvLJ9FEt+H5hxrF+bjJXFUAxOWHjFUwQfhG8ilVpkFfmKOaLlPe4MkytBvgqTN2a6I75WgErwOH7f+nvRWrVHuWfdjMsDkzcPF1xbV/I/r4Xpwf3M1MPpfJ1j5/FaFaKjPWXNVOstspgqmBHr7kIvRnevlhT5XsgkoeU4dchchFRPDy+gp6Kx/iKV6/b8DA8JhnlOv" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=debug msg="completed challenge" Feb 18 19:53:22 volumio go-librespot[3817]: time="2026-02-18T19:53:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:24 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:24 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631074. Feb 18 19:53:25 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:25 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:25 volumio go-librespot[3841]: go-librespot daemon starting... Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=debug msg="app state loaded" Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:25 volumio go-librespot[3841]: time="2026-02-18T19:53:25+07:00" level=info msg="zeroconf server listening on port 35105" Feb 18 19:53:26 volumio go-librespot[3841]: time="2026-02-18T19:53:26+07:00" level=debug msg="obtained new client token: AACZJsCwK3J9CPoYbKMlT0AjFAg/ItPUL030d+1CvJ0SVFEdzjyyk4VhHJRyQAgijiE8Fic8F283dJKOag6dFgE+dUo2IQIHiYhfdTwfZ3lq59EjVYxTCMqdQMOa14E300b+tCqhuE4t7DKxYWy25T3Eh5ncFt5B1G9lW6sqXCKnR5jCASG0mDrMdTuPj2wBIG/mmScEr7zPQmjF2175AoCPhhUKLYwJcwalrUuEDf/fEkYiXg4cLg==" Feb 18 19:53:26 volumio go-librespot[3841]: time="2026-02-18T19:53:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:26 volumio go-librespot[3841]: time="2026-02-18T19:53:26+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:26 volumio go-librespot[3841]: time="2026-02-18T19:53:26+07:00" level=debug msg="completed challenge" Feb 18 19:53:26 volumio go-librespot[3841]: time="2026-02-18T19:53:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:27 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:27 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631075. Feb 18 19:53:29 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:29 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:29 volumio go-librespot[3848]: go-librespot daemon starting... Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="app state loaded" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=info msg="zeroconf server listening on port 43123" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="obtained new client token: AABxporkcF8VLM2i//30phPt+BoXK6Foot0Tg9uGwj689VuNgen1IGODOEDyWFtt4DBSe04olidzYCQQ84wUGf6XK0MookzIQBA88Y79f+3VZPFoRzzJsY5anDty7JfxJl19yp76g51/86qzJSv08IdaHHbdycTXnxcq+/d3XHL6CPyDUwZkvy+vixSV576StOP4HYT2rlLaBu2l/ErlJlBxgTomuqaebTsMMJ3pR3fr92OhnEL/9bRB" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=debug msg="completed challenge" Feb 18 19:53:29 volumio go-librespot[3848]: time="2026-02-18T19:53:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:30 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:30 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631076. Feb 18 19:53:32 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:32 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:32 volumio go-librespot[3855]: go-librespot daemon starting... Feb 18 19:53:32 volumio go-librespot[3855]: time="2026-02-18T19:53:32+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:32 volumio go-librespot[3855]: time="2026-02-18T19:53:32+07:00" level=debug msg="app state loaded" Feb 18 19:53:32 volumio go-librespot[3855]: time="2026-02-18T19:53:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=info msg="zeroconf server listening on port 33479" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="obtained new client token: AABzw31+FahjWREVFK4uIMSEOXW8zkiJ1eG7N+V1mI8RDjnVixzYsbSObDCXoNHUTgab/DlK9Q261qQdoV3uxnbXuWriWdaV9qtscyqEZkXg+DkZZeDCgtWM2gI8GaAS4BV+ryihYOyn6zyxnQaJJ6ep20xJRkfX1RE5FUw6LlfvuVar7ibJCN03SJkWJ3NouexrN1ovD9+8uxnvGxZDSIUp/wuR4IViWG34QmgULSiq/ZZBaW62qA==" Feb 18 19:53:33 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="new websocket client" Feb 18 19:53:33 volumio volumio[1068]: info: Connection to go-librespot Websocket established Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=debug msg="completed challenge" Feb 18 19:53:33 volumio go-librespot[3855]: time="2026-02-18T19:53:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:33 volumio volumio[1068]: info: Connection to go-librespot Websocket closed Feb 18 19:53:36 volumio volumio[1068]: info: Getting Spotify volume Feb 18 19:53:36 volumio volumio[1068]: (node:1068) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:36 volumio volumio[1068]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 18 19:53:36 volumio volumio[1068]: (node:1068) 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: 117617) Feb 18 19:53:36 volumio volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 18 19:53:36 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:36 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:36 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 19:53:36 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:36 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631077. Feb 18 19:53:36 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:36 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:36 volumio go-librespot[3876]: go-librespot daemon starting... Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=debug msg="app state loaded" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=info msg="zeroconf server listening on port 40311" Feb 18 19:53:36 volumio go-librespot[3876]: time="2026-02-18T19:53:36+07:00" level=debug msg="obtained new client token: AAB3wxn+0PDDEhv2aT0zPUd9EiGxKsUG1h/kWRVaL10DPD9WeOVR4rBfGrgy5aIYII6WpmxvjHLdDipMNIAJ6NpD37t6aFj/zU4Qwuz3JZ4r/p0TuwITe3Lmd2p6L77tfSFM2IoJScOHiBNwmdQeomSRwQqCOwqZbd4j/CHcNhPiLBl9i0fLzlAyXJWiqXiJYbfIwXJuYtDKb+s2YT1OwWp2GjY0KDzgJEmdLG+8HC9NsAedvNFwOK+P" Feb 18 19:53:37 volumio go-librespot[3876]: time="2026-02-18T19:53:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:37 volumio go-librespot[3876]: time="2026-02-18T19:53:37+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:37 volumio go-librespot[3876]: time="2026-02-18T19:53:37+07:00" level=debug msg="completed challenge" Feb 18 19:53:37 volumio go-librespot[3876]: time="2026-02-18T19:53:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:39 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:39 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631078. Feb 18 19:53:40 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:40 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:40 volumio go-librespot[3883]: go-librespot daemon starting... Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=debug msg="app state loaded" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=info msg="zeroconf server listening on port 43463" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=debug msg="obtained new client token: AAA/s7QOQiobsrv29IiAjumv3/8joeNrv+Nk826eySupuGMN9ilu2xeJw5/AxG8e7NN1ib7PY1rh7NGVD5ZoM+85szboRe9/rp0DjISIqy+bedRnP/6UtIJZ8vWO9L9b2oiXLCVX/rG9oPA2u2FuLQzxpIuAXZz9CnHgtTk0o6qBVUREnmc0J5L/Rz1nWDcW+GK6uiDQTLoEtKntRHQpwwHiv8EtbZA6ml9/a0Qtpp44gQcRgVISeVHK" Feb 18 19:53:40 volumio go-librespot[3883]: time="2026-02-18T19:53:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:41 volumio go-librespot[3883]: time="2026-02-18T19:53:41+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:41 volumio go-librespot[3883]: time="2026-02-18T19:53:41+07:00" level=debug msg="completed challenge" Feb 18 19:53:41 volumio go-librespot[3883]: time="2026-02-18T19:53:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:42 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:42 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631079. Feb 18 19:53:44 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:44 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:44 volumio go-librespot[3904]: go-librespot daemon starting... Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="app state loaded" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=info msg="zeroconf server listening on port 34621" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="obtained new client token: AADA3eTSSFPsWJfFSgL+GmVJxQt6CCw0X94AN3Fs0jchdr9wgv3dlu5pICLq9QL3dTKwsYbjsodxVDXWCh2XKh1R+JXFEdANOZjUW39Von88FEOB061E7AixER3r1UNy8UO4klWpMiSOcuTpGnqr1ZSCtnH6eScwUXZsitVBNqLAo1RnkshGy+OcmIrxYsdrQXNz0I32RcNwij8fBuO4BQLTiaZ6lZcH453kZ6LbiAxVt9xrCTzZxJb2" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=debug msg="completed challenge" Feb 18 19:53:44 volumio go-librespot[3904]: time="2026-02-18T19:53:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:45 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:45 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:45 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:45 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 18 19:53:45 volumio sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 18 19:53:45 volumio sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:45 volumio sudo[3916]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:45 volumio sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 18 19:53:45 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:45 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:45 volumio sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 18 19:53:45 volumio sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:45 volumio sudo[3931]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:45 volumio sudo[3936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 18 19:53:45 volumio sudo[3936]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:45 volumio sudo[3936]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:45 volumio sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 19:53:45 volumio sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:45 volumio sudo[3942]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:45 volumio sudo[3945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 19:53:45 volumio sudo[3945]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:45 volumio sudo[3945]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:45 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:45 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:45 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:45 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:46 volumio sudo[3948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 19:53:46 volumio sudo[3948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:46 volumio sudo[3948]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:46 volumio sudo[3951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 19:53:46 volumio sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:46 volumio sudo[3951]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:46 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.14 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:46 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 19:53:46 volumio sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 19:53:46 volumio sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:46 volumio sudo[3954]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:46 volumio sudo[3957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 19:53:46 volumio sudo[3957]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:46 volumio sudo[3957]: pam_unix(sudo:session): session closed for user root Feb 18 19:53:46 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.14 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:46 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 19:53:46 volumio volumio[1068]: info: Listing playlists Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:46 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:46 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:46 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.14 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 19:53:46 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 19:53:47 volumio volumio[1068]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 19:53:47 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 19:53:47 volumio volumio[1068]: info: CoreCommandRouter::volumioGetQueue Feb 18 19:53:47 volumio volumio[1068]: info: CoreStateMachine::getQueue Feb 18 19:53:47 volumio volumio[1068]: info: CorePlayQueue::getQueue Feb 18 19:53:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631080. Feb 18 19:53:48 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:48 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:48 volumio go-librespot[3959]: go-librespot daemon starting... Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="app state loaded" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:48 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="new websocket client" Feb 18 19:53:48 volumio volumio[1068]: info: Connection to go-librespot Websocket established Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=info msg="zeroconf server listening on port 42591" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="obtained new client token: AADqKLwIle3natnsbjlMyFXnj4fMHaMA76h3QTIi8spx2HZezQ2lUuRmoPsDvnoXj83DTNUK8PpuWm82E7Nf5Un6Bt17fLni5oDltKcaNJxLTn1KRMXFsrzruyrvgOL43Wtk7mAugum+CQ9SkBuyyDPXQ5rvRJRxflivQqqWM/PBFpGFDUPq+f/mxhcr5pOegFH6GNTvQBxzUiMYZg+yBureGBWzI1EF4/JHDHGOe4ubZ70SO8iVRx2s" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=debug msg="completed challenge" Feb 18 19:53:48 volumio go-librespot[3959]: time="2026-02-18T19:53:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:48 volumio volumio[1068]: info: Connection to go-librespot Websocket closed Feb 18 19:53:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 19:53:50 volumio volumio[1068]: info: Retrieving Cloud Streaming UI Feb 18 19:53:50 volumio volumio[1068]: info: Getting Tidal Cloud Configuration Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 19:53:50 volumio volumio[1068]: info: Getting Qobuz Cloud Configuration Feb 18 19:53:50 volumio volumio[1068]: info: Asking plugin for UI Config Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 19:53:50 volumio volumio[1068]: info: Getting Spotify Cloud Configuration Feb 18 19:53:50 volumio volumio[1068]: info: Asking plugin for UI Config Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 19:53:50 volumio volumio[1068]: info: Saving Spotify Acccount Feb 18 19:53:50 volumio volumio[1068]: info: Got Tidal Cloud Configuration Feb 18 19:53:50 volumio volumio[1068]: info: Got it Feb 18 19:53:50 volumio volumio[1068]: info: Got it Feb 18 19:53:50 volumio volumio[1068]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 18 19:53:50 volumio volumio[1068]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 19:53:50 volumio volumio[1068]: info: Received Get System Info Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:50 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:50 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 19:53:50 volumio volumio[1068]: info: Received Get System Info Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 19:53:50 volumio volumio[1068]: info: Discovery: Getting this device information Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:50 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:50 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 19:53:51 volumio volumio[1068]: info: Getting Spotify volume Feb 18 19:53:51 volumio volumio[1068]: (node:1068) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:51 volumio volumio[1068]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Feb 18 19:53:51 volumio volumio[1068]: (node:1068) 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: 117618) Feb 18 19:53:51 volumio volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 18 19:53:51 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 18 19:53:51 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 18 19:53:51 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 19:53:51 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:51 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631081. Feb 18 19:53:52 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:52 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:52 volumio go-librespot[3966]: go-librespot daemon starting... Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="app state loaded" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=info msg="zeroconf server listening on port 44447" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="obtained new client token: AAARQcGsDL/SLTQykYyHrj1thLZmFRtcjvhsZA3uUBv4SauJnYd3LOQzVcWAhqYFvryfgOeYQ2YvN6iSGF/rffyDHhaF0sxpy5Kf3ZEdljxcsheW88q+uiIYX4FwexVCtaq0DqFKp8T3eGM/hFMzUMbn6DpXP4HsgzpBE1DbVtU6k6aiRq2Fc5sb4khY3WDeZfhFe6564qbYpQlJw4oQNdlhJQTtq9W3kJYSjSGSqyWLzKLYh4aTYD+J" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=debug msg="completed challenge" Feb 18 19:53:52 volumio go-librespot[3966]: time="2026-02-18T19:53:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:52 volumio volumio[1068]: info: Disabling MyMusic plugin upnp Feb 18 19:53:52 volumio sudo[3974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 18 19:53:52 volumio sudo[3974]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 19:53:52 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 18 19:53:52 volumio volumio[1068]: error: Upnp client error: Error: This socket has been ended by the other party Feb 18 19:53:54 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 19:53:54 volumio volumio[1068]: info: Initializing connection to go-librespot Websocket Feb 18 19:53:54 volumio volumio[1068]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 19:53:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 19:53:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 631082. Feb 18 19:53:55 volumio systemd[1]: Stopped go-librespot Daemon. Feb 18 19:53:55 volumio systemd[1]: Started go-librespot Daemon. Feb 18 19:53:55 volumio go-librespot[3991]: go-librespot daemon starting... Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=info msg="running go-librespot 0.2.0" Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=debug msg="app state loaded" Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 19:53:55 volumio go-librespot[3991]: time="2026-02-18T19:53:55+07:00" level=info msg="zeroconf server listening on port 38607" Feb 18 19:53:56 volumio go-librespot[3991]: time="2026-02-18T19:53:56+07:00" level=debug msg="obtained new client token: AAA1OnU2dvyCxSXa4fik/6utDQlAi1uPaoE5AB4rmnvBcW4dK8PG2dMnJQkfGRrMvRw3SKa9tyXO+0phCa4sKBX+9zTBP+F2FAJFxBmP6g5m7pYFNCn1iBRgOUVfd6kc3VhuPl13S7U0bN7ddYy7x0czcbU3vyONNeieiTZI/cOHQwX27ENPOQXysust2HXY2+twWqTkTX2AXID8kYNyRfIbHdeBnOv9/iwMmK+abSIH+JBkMr52ow==" Feb 18 19:53:56 volumio go-librespot[3991]: time="2026-02-18T19:53:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 19:53:56 volumio go-librespot[3991]: time="2026-02-18T19:53:56+07:00" level=debug msg="completed keyexchange" Feb 18 19:53:56 volumio go-librespot[3991]: time="2026-02-18T19:53:56+07:00" level=debug msg="completed challenge" Feb 18 19:53:56 volumio go-librespot[3991]: time="2026-02-18T19:53:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 19:53:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 19:53:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 19:53:57 volumio volumio[1068]: info: Enabling MyMusic plugin upnp Feb 18 19:53:57 volumio volumio[1068]: info: Enabling plugin upnp Feb 18 19:53:57 volumio volumio[1068]: info: Loading plugin "upnp"... Feb 18 19:53:57 volumio volumio[1068]: info: [1771419237437] Starting Upmpd Daemon Feb 18 19:53:57 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 19:53:57 volumio volumio[1068]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 19:53:57 volumio volumio[1068]: Error: listen EADDRINUSE: address already in use :::6599 Feb 18 19:53:57 volumio volumio[1068]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Feb 18 19:53:57 volumio volumio[1068]: at listenInCluster (net.js:1379:12) Feb 18 19:53:57 volumio volumio[1068]: at Server.listen (net.js:1465:7) Feb 18 19:53:57 volumio volumio[1068]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 18 19:53:57 volumio volumio[1068]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 18 19:53:57 volumio volumio[1068]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 18 19:53:57 volumio volumio[1068]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 18 19:53:57 volumio volumio[1068]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Feb 18 19:53:57 volumio volumio[1068]: code: 'EADDRINUSE', Feb 18 19:53:57 volumio volumio[1068]: errno: -98, Feb 18 19:53:57 volumio volumio[1068]: syscall: 'listen', Feb 18 19:53:57 volumio volumio[1068]: address: '::', Feb 18 19:53:57 volumio volumio[1068]: port: 6599 Feb 18 19:53:57 volumio volumio[1068]: } Feb 18 19:53:57 volumio volumio[1068]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 19:53:57 volumio sudo[4007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-18 19:52 Feb 18 19:53:57 volumio sudo[4007]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:31:13 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="674d725e3f749f2a23785c9f94a28aa3"