Feb 11 17:50:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 11 17:50:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:02 volumio go-librespot[7445]: go-librespot daemon starting... Feb 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+07:00" level=debug msg="app state loaded" Feb 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+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 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+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 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+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 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+07:00" level=info msg="zeroconf server listening on port 44173" Feb 11 17:50:02 volumio go-librespot[7446]: time="2026-02-11T17:50:02+07:00" level=debug msg="obtained new client token: AABo++cEWHbGVHWg1Ub6+AbvvcVr6FGelijS22OJDxlgRrfXVykWNb6p62uKS2Kxypxo4aaTCgMRrVGiv7vI2pHnyg5OwnEWZXYlnGMNcp872HVScQx2TUIPivUOypFa4JEIjUSmnBz5cpk/e5W9ACToRRvL9DRPNlvV0K6gjmCEuIdZ1jhanDkKFqgHJUQ4ufLxb964A9T8Wk+Hq14tDTF0Kxfyn08/GfA/nKg9AnB/NANHMMNoFtX7XQ==" Feb 11 17:50:03 volumio go-librespot[7446]: time="2026-02-11T17:50:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:03 volumio go-librespot[7446]: time="2026-02-11T17:50:03+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:03 volumio go-librespot[7446]: time="2026-02-11T17:50:03+07:00" level=debug msg="completed challenge" Feb 11 17:50:03 volumio go-librespot[7446]: time="2026-02-11T17:50: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 11 17:50:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 11 17:50:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:06 volumio go-librespot[7454]: go-librespot daemon starting... Feb 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+07:00" level=debug msg="app state loaded" Feb 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+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 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+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 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+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 11 17:50:06 volumio go-librespot[7455]: time="2026-02-11T17:50:06+07:00" level=info msg="zeroconf server listening on port 40535" Feb 11 17:50:07 volumio go-librespot[7455]: time="2026-02-11T17:50:07+07:00" level=debug msg="obtained new client token: AAD6F+1NaL5tzHfgW7rHnLFHkHiuSt5MlLD4bMUG6kZ9h0qP6YSaQ4kSC6eatbaiHNeQHmXtE94becn5J2AE8og753Ou62Om6w0NvONj6zqNHnzQgqFcxXKNJARoMTuwv8X5UpjqL8ryvwyHhh5e4Ub0Hwh77cQ7TzhspOhFDpXRPIDQ/c5SpIrGYwOgGxYzVTFDqLxoOTJtst6cFQNSunujaO6w3k9ynp7lQ7fkN/IGjZTGaRWyjms=" Feb 11 17:50:07 volumio go-librespot[7455]: time="2026-02-11T17:50:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:07 volumio go-librespot[7455]: time="2026-02-11T17:50:07+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:07 volumio go-librespot[7455]: time="2026-02-11T17:50:07+07:00" level=debug msg="completed challenge" Feb 11 17:50:07 volumio go-librespot[7455]: time="2026-02-11T17:50: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 11 17:50:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:10 volumio volumio-remote-updater[643]: [2026-02-11 17:50:10] [connect] Successful connection Feb 11 17:50:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 11 17:50:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:10 volumio go-librespot[7476]: go-librespot daemon starting... Feb 11 17:50:10 volumio go-librespot[7477]: time="2026-02-11T17:50:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:10 volumio go-librespot[7477]: time="2026-02-11T17:50:10+07:00" level=debug msg="app state loaded" Feb 11 17:50:10 volumio go-librespot[7477]: time="2026-02-11T17:50:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50: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-gew1.spotify.com:80]" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+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 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+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 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+07:00" level=info msg="zeroconf server listening on port 43029" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+07:00" level=debug msg="obtained new client token: AADUlos8noUbGdYoeWa/VLGR/zvOuHG135EcxielPZtCsVAreppIhS/6TNzEWcbPuIPp+65dQCJVFxZw+k6C2dbBRZihxgwtvaFgJW7ZaW0Q50ltIJX6D7KILOW+UrO2olLWJir817mawT9th60X15gJa2SP7wYgJ1lluNij2Ybcheqx+Qd6B4hYi37tlFRcAad1+lVdMn8a7TgRq0am4QD+oUEZ0jcOcMwKMnktSTUY/F+dbCCBjpdxpQ==" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50:11+07:00" level=debug msg="completed challenge" Feb 11 17:50:11 volumio go-librespot[7477]: time="2026-02-11T17:50: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 11 17:50:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 11 17:50:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:14 volumio go-librespot[7485]: go-librespot daemon starting... Feb 11 17:50:14 volumio go-librespot[7486]: time="2026-02-11T17:50:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:14 volumio go-librespot[7486]: time="2026-02-11T17:50:14+07:00" level=debug msg="app state loaded" Feb 11 17:50:14 volumio go-librespot[7486]: time="2026-02-11T17:50:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+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 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+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 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+07:00" level=info msg="zeroconf server listening on port 39325" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+07:00" level=debug msg="obtained new client token: AADrVBsta4k6vBcv8dH1lZihdUue56mOyZuSaatQZK1BtW0uql3rX8xKiXg2og/l5oBlLy4jqWaqwvVy5mXDG5nOUmgDCS1tBU+goybjt0chil4KpdRwKsTxJ0jIrdkpQViyV2OHa3bYHxdSfgn5oPSpKZxjoNH2QGdKdR41XsLUPwkueA97acyWmdBeK36/gQQvOHPnD5nZLTksZ8Ysdlw5zV0eY4Qq+toEtj2lDj3v7TXc62Jz1iyOQA==" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50:15+07:00" level=debug msg="completed challenge" Feb 11 17:50:15 volumio go-librespot[7486]: time="2026-02-11T17:50: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 11 17:50:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 11 17:50:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:19 volumio go-librespot[7508]: go-librespot daemon starting... Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+07:00" level=debug msg="app state loaded" Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+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 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+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 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+07:00" level=info msg="zeroconf server listening on port 42609" Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+07:00" level=debug msg="obtained new client token: AACY97mcic4BeyLXVIia9zMxOfkfxgGzXHu7Qzin0P9HZrS626X7XSrhTKpWLbo7aHwTqwKHjmzFYSqCT52MzW/COE4AFezIoyHat8iZP8oPectZrVSSA+JrlhTFkMebvBevOOztsr/9rT5JIoFPsdIMnFwWAW6xvZQWCR7+ry2U+GHeFmJpxkLPnJVbfTSJDcmtwnDMmcTjbNa4Axzr8IOzoKPhhD0/puLJkv8vK8G36FEpA7aVM7P4wg==" Feb 11 17:50:19 volumio go-librespot[7509]: time="2026-02-11T17:50:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:20 volumio go-librespot[7509]: time="2026-02-11T17:50:20+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:20 volumio go-librespot[7509]: time="2026-02-11T17:50:20+07:00" level=debug msg="completed challenge" Feb 11 17:50:20 volumio go-librespot[7509]: time="2026-02-11T17:50:20+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 11 17:50:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 11 17:50:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:23 volumio go-librespot[7516]: go-librespot daemon starting... Feb 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+07:00" level=debug msg="app state loaded" Feb 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+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 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+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 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+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 11 17:50:23 volumio go-librespot[7517]: time="2026-02-11T17:50:23+07:00" level=info msg="zeroconf server listening on port 40197" Feb 11 17:50:24 volumio go-librespot[7517]: time="2026-02-11T17:50:24+07:00" level=debug msg="obtained new client token: AABvlmFa1/kCbayrSnDFemoabSI7TjIEJvzIOaLPbljxunjdR3ZYcNKA/b8c8guYFXFyne4cCgT0LFfdu6W7HURRQOPw/DMhQQlkTgiQGV6kOk0Vl7bOqaqQ896CPmgIA0NgGwgb/eDHng/U186K7cgHAhfaORd5fPrNkDKRPA3P5SBoztwmueqKaD5LvztSMtGYT+ICY0xWhJQrLm9aIByLyZti2w7kdq+UQsGtFUlWoKdB7rsVY9A=" Feb 11 17:50:24 volumio go-librespot[7517]: time="2026-02-11T17:50:24+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 11 17:50:24 volumio go-librespot[7517]: time="2026-02-11T17:50:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 11 17:50:24 volumio go-librespot[7517]: time="2026-02-11T17:50:24+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:24 volumio go-librespot[7517]: time="2026-02-11T17:50:24+07:00" level=debug msg="completed challenge" Feb 11 17:50:24 volumio go-librespot[7517]: time="2026-02-11T17:50:24+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 11 17:50:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:25 volumio volumio-remote-updater[643]: [2026-02-11 17:50:25] [connect] Successful connection Feb 11 17:50:26 volumio nmbd[966]: [2026/02/11 17:50:26.062577, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 11 17:50:26 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 11 17:50:26 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 11 17:50:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 11 17:50:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:27 volumio go-librespot[7539]: go-librespot daemon starting... Feb 11 17:50:27 volumio go-librespot[7540]: time="2026-02-11T17:50:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:27 volumio go-librespot[7540]: time="2026-02-11T17:50:27+07:00" level=debug msg="app state loaded" Feb 11 17:50:27 volumio go-librespot[7540]: time="2026-02-11T17:50:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+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 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+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 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+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 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+07:00" level=info msg="zeroconf server listening on port 35739" Feb 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+07:00" level=debug msg="obtained new client token: AAA26NKDo0AJ1t29/hkmJVd0B6dzTXXvPOnEsqFAHp6ChPgUAIwc3kILA9/sNe4Eo1zEoAwzIJzDMzCose9zv5iVdqx2uve+fqCr8NmGjtfQdbHttzXniNSUpxYL2x87NiK9ZgyZNbJv+5pEFkJnPz+9bFTwiod8lNMorC9ouTPYRP46eYpfc6s746jwyan2bYa4l3SJWok6dmu1IuStN+gRpyHcNok7YTA2TAAAKSG8UWNI8MPtXtKdhw==" Feb 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+07:00" level=debug msg="completed challenge" Feb 11 17:50:28 volumio go-librespot[7540]: time="2026-02-11T17:50:28+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 11 17:50:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 11 17:50:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:31 volumio go-librespot[7547]: go-librespot daemon starting... Feb 11 17:50:31 volumio go-librespot[7548]: time="2026-02-11T17:50:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:31 volumio go-librespot[7548]: time="2026-02-11T17:50:31+07:00" level=debug msg="app state loaded" Feb 11 17:50:31 volumio go-librespot[7548]: time="2026-02-11T17:50:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+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 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+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 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+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 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+07:00" level=info msg="zeroconf server listening on port 38327" Feb 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+07:00" level=debug msg="obtained new client token: AACV4+DLNoDwU1n3xIRP7b7KN65cOXmDFjZVwlbYEEigdKJioyz5jso5LBXZdcj8ojUkzOd1jxWX1hovwJPktim9HEhPzJBf+ayamCECORVEsBymYuNucjTbN/qN6WBpI6SVIq5zHXMV0A+4YGd1OVvHv6F3yYRVzXTykQ9pYHCwPsxs6lcY+blTwqCuge3NZrBKzW18wu10BYQi0txGguMXspsTZuKCU6ypwz+FmEw5MgJhrKOiqSYnhA==" Feb 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+07:00" level=debug msg="completed challenge" Feb 11 17:50:32 volumio go-librespot[7548]: time="2026-02-11T17:50:32+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 11 17:50:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 11 17:50:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:36 volumio go-librespot[7555]: go-librespot daemon starting... Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+07:00" level=debug msg="app state loaded" Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+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 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+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 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+07:00" level=info msg="zeroconf server listening on port 39113" Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+07:00" level=debug msg="obtained new client token: AADFTV84F3/ZHTv+84XcoZmGBXT4UEEtrxoaQznF0ileeaThG0EY++Gkit021EObGropnPWjUxOS69CS9jodB+m+eBLWft/mDhJlgeAzEYBRxwKl2S+tSHNZ24RH3CHYlKEd4KOIPakHqjE2AYj4oHnv9appqcwHMb/bp4gMB58OizupRZYcXxT3f4e1uHd12N5HKGINRg4rLkgbbcfU9iG9L3iTFG5tYEhIuWtXiWTc67Wm07dS6NeBXw==" Feb 11 17:50:36 volumio go-librespot[7556]: time="2026-02-11T17:50:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:37 volumio go-librespot[7556]: time="2026-02-11T17:50:37+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:37 volumio go-librespot[7556]: time="2026-02-11T17:50:37+07:00" level=debug msg="completed challenge" Feb 11 17:50:37 volumio go-librespot[7556]: time="2026-02-11T17:50: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 11 17:50:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 11 17:50:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:40 volumio go-librespot[7578]: go-librespot daemon starting... Feb 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50:40+07:00" level=debug msg="app state loaded" Feb 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:40 volumio volumio-remote-updater[643]: [2026-02-11 17:50:40] [connect] Successful connection Feb 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50:40+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 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50:40+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 11 17:50:40 volumio go-librespot[7579]: time="2026-02-11T17:50:40+07:00" level=info msg="zeroconf server listening on port 39519" Feb 11 17:50:41 volumio go-librespot[7579]: time="2026-02-11T17:50:41+07:00" level=debug msg="obtained new client token: AABkoXSJoUNJIeeD6wo1hX3RE2TAUddluG04jRkqYG/f3P9Wo8EeC5sSM0MuGNobw0Lck6LJ7p9TQseN/F7wNSjus+6oy6E4+3BE2RhttyNGfGv3C09rtF9dIHcHqM7LA9q+K2l1A5j3o5MHqiCnq6L7hivVEZvti9PDuHDhfFHRaHJcIH1Mh+7rSJwbLG9+sVvlylGzuz0anMN2aZl1U2akgV47c8Ya5xV4bilqc8MRVLoNRuiGBLE=" Feb 11 17:50:41 volumio go-librespot[7579]: time="2026-02-11T17:50:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:41 volumio go-librespot[7579]: time="2026-02-11T17:50:41+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:41 volumio go-librespot[7579]: time="2026-02-11T17:50:41+07:00" level=debug msg="completed challenge" Feb 11 17:50:41 volumio go-librespot[7579]: time="2026-02-11T17:50: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 11 17:50:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 11 17:50:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:44 volumio go-librespot[7586]: go-librespot daemon starting... Feb 11 17:50:44 volumio go-librespot[7587]: time="2026-02-11T17:50:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:44 volumio go-librespot[7587]: time="2026-02-11T17:50:44+07:00" level=debug msg="app state loaded" Feb 11 17:50:44 volumio go-librespot[7587]: time="2026-02-11T17:50:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+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 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+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 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+07:00" level=info msg="zeroconf server listening on port 35783" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+07:00" level=debug msg="obtained new client token: AAA76EnI0Js0NiDxMchrbBSz7rEAzxQi9DOCYtF1N9bDl+RH8bCH1OJiZ4pLpU3DB6te7+/pZFvlOTzoxRj8smEh+1co9wtAJsbw/1uG7Q2j28YpIWMgzUBSttqwRL2q8i79pP9LTuxL0LfrTBgaMX1c+IYK1/NKXsxrJxXglW05g+Zljmhdp9o6CB2gzIYKZ2Hy3FNkqw0WzTDACVyrNcYwaZ6YkSIFFsbl6igJiN7IanUSZVOT1Rzhbw==" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50:45+07:00" level=debug msg="completed challenge" Feb 11 17:50:45 volumio go-librespot[7587]: time="2026-02-11T17:50: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 11 17:50:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Feb 11 17:50:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:48 volumio go-librespot[7609]: go-librespot daemon starting... Feb 11 17:50:48 volumio go-librespot[7610]: time="2026-02-11T17:50:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:48 volumio go-librespot[7610]: time="2026-02-11T17:50:48+07:00" level=debug msg="app state loaded" Feb 11 17:50:48 volumio go-librespot[7610]: time="2026-02-11T17:50:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+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 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+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 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+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 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+07:00" level=info msg="zeroconf server listening on port 37553" Feb 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+07:00" level=debug msg="obtained new client token: AAAARdF3JZEbWCX0VUBUT5E/a/6J9C69I6RS7AI9OAqvrqgijxtYIZe4byuFz84M481kxKKMd9v0ymVOdpnoHXPoLhzgxWc9i5GGmAje9WdKzeFCOTiUvjRVFkNNPzhyub5XqGi8e5thiZ6vVzYVRY8ZAW1lTijq+880/ZWhw9TrPcYeZnI3xNoVdu6Yn+uyLcoiZ+rk139EIvSYfZGgbEu3gxfXrSg8iuzIteyRGoslNzwq2CvpBqnRhw==" Feb 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50:49+07:00" level=debug msg="completed challenge" Feb 11 17:50:49 volumio go-librespot[7610]: time="2026-02-11T17:50: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 11 17:50:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Feb 11 17:50:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:53 volumio go-librespot[7617]: go-librespot daemon starting... Feb 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+07:00" level=debug msg="app state loaded" Feb 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+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 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+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 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+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 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+07:00" level=info msg="zeroconf server listening on port 38961" Feb 11 17:50:53 volumio go-librespot[7618]: time="2026-02-11T17:50:53+07:00" level=debug msg="obtained new client token: AABhzl2kGTD1XZ5qjFNGSIliOSxYo9Ycvhg1QTR9OsFa3OmRT6jXxulCTybss9Wch+bSRTo1WDgvc3wTWdKlEKe657z4LyjvVHpcDfNHOqBQcHUOX7vZG1X/ClpHtsgqfKD3D29k+bF6TfplL1K2ksJ+7vz3fTItOzolWM3UYUuwAxTRWiPgXowsJj7HIPEI2b1Q70HtlYgY5zdzyNp3kka5l11KSCmC59BFx2Cu/+1NDQY5mXWPWJ3KKA==" Feb 11 17:50:54 volumio go-librespot[7618]: time="2026-02-11T17:50:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:54 volumio go-librespot[7618]: time="2026-02-11T17:50:54+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:54 volumio go-librespot[7618]: time="2026-02-11T17:50:54+07:00" level=debug msg="completed challenge" Feb 11 17:50:54 volumio go-librespot[7618]: time="2026-02-11T17:50:54+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 11 17:50:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:50:55 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:55 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 11 17:50:55 volumio systemd[1]: volumio.service: Consumed 1min 255ms CPU time. Feb 11 17:50:55 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 17:50:55 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 17:50:55 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12020. Feb 11 17:50:55 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 17:50:55 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 11 17:50:55 volumio systemd[1]: volumio.service: Consumed 1min 255ms CPU time. Feb 11 17:50:55 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 11 17:50:55 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 17:50:55 volumio volumio-remote-updater[643]: [2026-02-11 17:50:55] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 11 17:50:55 volumio volumio-remote-updater[643]: [2026-02-11 17:50:55] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 11 17:50:55 volumio volumio-remote-updater[643]: [2026-02-11 17:50:55] [error] handle_connect error: Connection refused Feb 11 17:50:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 11 17:50:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:50:58 volumio go-librespot[7658]: go-librespot daemon starting... Feb 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+07:00" level=debug msg="app state loaded" Feb 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+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 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+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 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+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 11 17:50:58 volumio go-librespot[7659]: time="2026-02-11T17:50:58+07:00" level=info msg="zeroconf server listening on port 42291" Feb 11 17:50:59 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:50:59 volumio volumio[7643]: info: ----- Volumio3 ---- Feb 11 17:50:59 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:50:59 volumio volumio[7643]: info: ----- System startup ---- Feb 11 17:50:59 volumio go-librespot[7659]: time="2026-02-11T17:50:59+07:00" level=debug msg="obtained new client token: AAAAfz7Jlo+XNo4y85OIKMpzxR2WJN1SI7Vf5EQ3FmJGB93/8ZQIlXDC7wV8zchfJx0NkHlrKdQ7fxgnMYBr5MUgyIwKO5qGqefrc/LXRcjbjxhqFzFvpAvUuXx+Awuv46GLZsfYBdOfMFEiyUBmiSaZWp54iVmTbHjFoh3RAKEyj3vrL3AvWHgHI4b9pLw5ZvUWT/z7YjGxeK+u83kDqhjmBf59s+QWUpG37tvF8CUF9SoQSMYU/8FDJQ==" Feb 11 17:50:59 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:50:59 volumio go-librespot[7659]: time="2026-02-11T17:50:59+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:50:59 volumio go-librespot[7659]: time="2026-02-11T17:50:59+07:00" level=debug msg="completed keyexchange" Feb 11 17:50:59 volumio go-librespot[7659]: time="2026-02-11T17:50:59+07:00" level=debug msg="completed challenge" Feb 11 17:50:59 volumio go-librespot[7659]: time="2026-02-11T17:50:59+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 11 17:50:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:50:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:00 volumio volumio[7643]: info: MYVOLUMIO Environment detected Feb 11 17:51:00 volumio volumio[7643]: info: Plugin folders cleanup Feb 11 17:51:00 volumio volumio[7643]: info: Scanning into folder /volumio/app/plugins/ Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category audio_interface Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category miscellanea Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category music_service Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category plugins.json Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category system_controller Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category user_interface Feb 11 17:51:00 volumio volumio[7643]: info: Scanning into folder /data/plugins/ Feb 11 17:51:00 volumio volumio[7643]: info: Scanning category music_service Feb 11 17:51:00 volumio volumio[7643]: info: Plugin folders cleanup completed Feb 11 17:51:00 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:51:00 volumio volumio[7643]: info: ----- Core plugins startup ---- Feb 11 17:51:00 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:51:00 volumio volumio[7643]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 17:51:00 volumio volumio[7643]: info: Adding plugin upnp to MyMusic Plugins Feb 11 17:51:00 volumio volumio[7643]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 17:51:00 volumio volumio[7643]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 17:51:00 volumio volumio[7643]: info: Loading plugins from folder /data/plugins/ Feb 11 17:51:00 volumio volumio[7643]: info: Loading plugin "system"... Feb 11 17:51:00 volumio volumio[7643]: info: Loading plugin "appearance"... Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "network"... Feb 11 17:51:02 volumio volumio[7643]: info: Refreshing Cached IP Addresses Feb 11 17:51:02 volumio sudo[7681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 17:51:02 volumio sudo[7681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 11 17:51:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:02 volumio sudo[7683]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 17:51:02 volumio sudo[7683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:02 volumio sudo[7681]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "services"... Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "alsa_controller"... Feb 11 17:51:02 volumio sudo[7683]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:02 volumio sudo[7693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 17:51:02 volumio sudo[7693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:02 volumio go-librespot[7696]: go-librespot daemon starting... Feb 11 17:51:02 volumio go-librespot[7709]: time="2026-02-11T17:51:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:02 volumio go-librespot[7709]: time="2026-02-11T17:51:02+07:00" level=debug msg="app state loaded" Feb 11 17:51:02 volumio go-librespot[7709]: time="2026-02-11T17:51:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:02 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "wizard"... Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "networkfs"... Feb 11 17:51:02 volumio volumio[7643]: info: Starting Udev Watcher for removable devices Feb 11 17:51:02 volumio volumio[7643]: info: Ignoring mount for partition: boot Feb 11 17:51:02 volumio volumio[7643]: info: Ignoring mount for partition: volumio Feb 11 17:51:02 volumio volumio[7643]: info: Ignoring mount for partition: volumio_data Feb 11 17:51:02 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "volumio_command_line_client"... Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "upnp"... Feb 11 17:51:02 volumio volumio[7643]: info: [1770807062917] Starting Upmpd Daemon Feb 11 17:51:02 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "my_music"... Feb 11 17:51:02 volumio volumio[7643]: info: Loading plugin "mpd"... Feb 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51: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 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51: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 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51: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 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51:03+07:00" level=info msg="zeroconf server listening on port 35133" Feb 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51:03+07:00" level=debug msg="obtained new client token: AADaSPuvymHVEVmj21nayKDbECJ15EVmvq9+IIRON/TGWl/b5ZaQOzaY2xStT7/0NkMMheD+Tu9eUOTfIGtkHFDKKBGVtSfpxN5VB24uDErXm5Gr2AhXM+qW2wLjpRjrWpgiv3ywEgP88Pr/kmp3N6VRhO2MYx2kx8PheXKDImQF3TCk8dA5r8XbXPiA9X/uIIt/+oevnXNrDdEGhf3OLfhSLtEc9il6Xtc2FBVu3bIyIvLH7IeV3JXwSQ==" Feb 11 17:51:03 volumio volumio[7643]: info: Loading plugin "upnp_browser"... Feb 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51:03+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51:03+07:00" level=debug msg="completed challenge" Feb 11 17:51:03 volumio go-librespot[7709]: time="2026-02-11T17:51: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 11 17:51:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:05 volumio sudo[7693]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:05 volumio volumio-remote-updater[643]: [2026-02-11 17:51:05] [connect] Successful connection Feb 11 17:51:06 volumio volumio[7643]: info: Starting UPNP Browser Feb 11 17:51:06 volumio volumio[7643]: info: Loading plugin "alarm-clock"... Feb 11 17:51:06 volumio volumio[7643]: info: Loading plugin "airplay_emulation"... Feb 11 17:51:06 volumio volumio[7643]: info: Starting Shairport Sync Feb 11 17:51:06 volumio volumio[7643]: info: Loading plugin "last_100"... Feb 11 17:51:06 volumio volumio[7643]: info: Loading plugin "webradio"... Feb 11 17:51:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 11 17:51:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:07 volumio volumio[7643]: info: Loading plugin "i2s_dacs"... Feb 11 17:51:07 volumio volumio[7643]: info: Loading plugin "volumiodiscovery"... Feb 11 17:51:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:07 volumio go-librespot[7722]: go-librespot daemon starting... Feb 11 17:51:07 volumio volumio[7643]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 17:51:07 volumio volumio[7643]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 17:51:07 volumio volumio[7643]: *** WARNING *** For more information see Feb 11 17:51:07 volumio volumio[7643]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 17:51:07 volumio volumio[7643]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 17:51:07 volumio volumio[7643]: *** WARNING *** For more information see Feb 11 17:51:07 volumio node[7643]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 17:51:07 volumio node[7643]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 17:51:07 volumio node[7643]: *** WARNING *** For more information see Feb 11 17:51:07 volumio node[7643]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 17:51:07 volumio node[7643]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 17:51:07 volumio node[7643]: *** WARNING *** For more information see Feb 11 17:51:07 volumio volumio[7643]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 17:51:07 volumio volumio[7643]: info: Discovery: Started advertising with name: Volumio Feb 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51:07+07:00" level=debug msg="app state loaded" Feb 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:07 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 17:51:07 volumio volumio[7643]: info: Loading plugin "spop"... Feb 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51:07+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 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51:07+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 11 17:51:07 volumio go-librespot[7723]: time="2026-02-11T17:51:07+07:00" level=info msg="zeroconf server listening on port 40221" Feb 11 17:51:08 volumio go-librespot[7723]: time="2026-02-11T17:51:08+07:00" level=debug msg="obtained new client token: AABs0FnpuATIoULln39EF4PwgS+pV/zvP966mA/rUO1KMembMCg1/IIQVqhoSIsk5lkTDDjz49TrjIaERXuCZLlICitNKLj6291yfKVKlKMsJu+o7zhNaA3yEZfRnKR9eWGqQ1GU6jFfM10NGJhixo0CC73Mg5INwY1k4bZckampUvX/enkqCHlviebiCi3PpgjL62gobohFM0vtavcFWDzBlrXkTWN09xU/wsp7Uhvexj3Ec1xZWhhAPQ==" Feb 11 17:51:08 volumio go-librespot[7723]: time="2026-02-11T17:51:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:08 volumio go-librespot[7723]: time="2026-02-11T17:51:08+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:08 volumio go-librespot[7723]: time="2026-02-11T17:51:08+07:00" level=debug msg="completed challenge" Feb 11 17:51:08 volumio go-librespot[7723]: time="2026-02-11T17:51: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 11 17:51:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:09 volumio volumio[7643]: info: Loading plugin "ytcr"... Feb 11 17:51:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 11 17:51:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:11 volumio go-librespot[7733]: go-librespot daemon starting... Feb 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51:11+07:00" level=debug msg="app state loaded" Feb 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:11 volumio volumio[7643]: info: Loading plugin "ytmusic"... Feb 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51:11+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 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51:11+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 11 17:51:11 volumio go-librespot[7734]: time="2026-02-11T17:51:11+07:00" level=info msg="zeroconf server listening on port 43771" Feb 11 17:51:12 volumio go-librespot[7734]: time="2026-02-11T17:51:12+07:00" level=debug msg="obtained new client token: AABDdGdSjyfsbxVcUrDI+DihFtk4bqTL7VvlUN7Y1q78L3GjgFqEOv4WJuH7s3LWcDvdVBOsrZ/ltsz+VewE5n1qKQbacKEFSD32T5XUWM2pJAUmoJVme/x7G2fflbNODoChISOTKaWKr60EKjoHtWQ//iUTAEvP3yogJOOsaRRD4oKNCtB3NzC3rsAEyiVjYjCwh9XagN1l6CRyEq0V05EDuTQ9eV8R2rMHFXPEM/b2ok0fyZg+NRQ=" Feb 11 17:51:12 volumio go-librespot[7734]: time="2026-02-11T17:51:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:12 volumio go-librespot[7734]: time="2026-02-11T17:51:12+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:12 volumio go-librespot[7734]: time="2026-02-11T17:51:12+07:00" level=debug msg="completed challenge" Feb 11 17:51:12 volumio go-librespot[7734]: time="2026-02-11T17:51: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 11 17:51:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:12 volumio volumio[7643]: info: Loading plugin "outputs"... Feb 11 17:51:12 volumio volumio[7643]: info: Loading plugin "albumart"... Feb 11 17:51:12 volumio volumio[7643]: info: Plugin example_plugin is not enabled Feb 11 17:51:12 volumio volumio[7643]: info: Loading plugin "inputs"... Feb 11 17:51:12 volumio volumio[7643]: info: Loading plugin "updater_comm"... Feb 11 17:51:13 volumio volumio[7643]: info: Plugin mpdemulation is not enabled Feb 11 17:51:13 volumio volumio[7643]: info: Loading plugin "rest_api"... Feb 11 17:51:13 volumio volumio[7643]: info: Loading plugin "websocket"... Feb 11 17:51:13 volumio volumio[7643]: info: Starting Socket.io Server version 1.7.4 Feb 11 17:51:13 volumio volumio[7643]: info: Loading plugin "RoonBridge"... Feb 11 17:51:13 volumio volumio[7643]: info: Applying required configuration parameters for plugin RoonBridge Feb 11 17:51:13 volumio volumio[7643]: info: Loading i18n strings for locale en Feb 11 17:51:13 volumio volumio[7643]: Updating browse sources language Feb 11 17:51:13 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:51:14 volumio volumio[7756]: Forking 3 albumart workers Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::initPlayerControls Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: Express server listening on port 3000 Feb 11 17:51:14 volumio volumio[7643]: [Metrics] WebUI: 16s 133.30ms Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::resetVolumioState Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::getcurrentVolume Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 17:51:14 volumio sudo[7799]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 17:51:14 volumio sudo[7802]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 17:51:14 volumio sudo[7799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:14 volumio sudo[7802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:14 volumio sudo[7802]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:14 volumio sudo[7799]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:14 volumio volumio[7643]: info: Volumio Network Manager: Network status updated: 1 Feb 11 17:51:14 volumio volumio[7643]: info: VolumeController:: Volume=100 Mute =false Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::pushState Feb 11 17:51:14 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::volumioPushState Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::updateTrackBlock Feb 11 17:51:14 volumio volumio[7643]: info: CorePlayQueue::getTrackBlock Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:14 volumio volumio[7643]: info: Reloading queue from file Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::setRepeat null single undefined Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::pushState Feb 11 17:51:14 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::volumioPushState Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::setRandom null Feb 11 17:51:14 volumio volumio[7643]: info: CoreStateMachine::pushState Feb 11 17:51:14 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:14 volumio volumio[7643]: info: CoreCommandRouter::volumioPushState Feb 11 17:51:15 volumio volumio[7643]: info: Setting Device type: Raspberry PI Feb 11 17:51:15 volumio volumio[7643]: info: Completed loading Core Plugins Feb 11 17:51:15 volumio volumio[7643]: info: Preparing to generate the ALSA configuration file Feb 11 17:51:15 volumio volumio[7643]: info: VolumeController:: Volume=100 Mute =false Feb 11 17:51:15 volumio volumio[7643]: info: CoreStateMachine::pushState Feb 11 17:51:15 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::volumioPushState Feb 11 17:51:15 volumio volumio[7643]: info: Asound.conf file unchanged, so no further update is needed Feb 11 17:51:15 volumio volumio[7643]: info: Output device has changed, restarting MPD Feb 11 17:51:15 volumio sudo[7816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 17:51:15 volumio sudo[7816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:15 volumio volumio[7643]: info: Output device has changed, restarting Shairport Sync Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:15 volumio sudo[7819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 17:51:15 volumio sudo[7819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:15 volumio sudo[7819]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:15 volumio sudo[7822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 17:51:15 volumio sudo[7822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:15 volumio volumio[7643]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 17:51:15 volumio volumio[7643]: info: ___________ START PLUGINS ___________ Feb 11 17:51:15 volumio volumio[7643]: info: ControllerMpd::onStart: Initializing MPD Feb 11 17:51:15 volumio volumio[7643]: info: Creating MPD Configuration file Feb 11 17:51:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 17:51:15 volumio sudo[7816]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 17:51:15 volumio volumio[7643]: info: [1770807075513] CoreMusicLibrary::Adding element Media Servers Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:51:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 11 17:51:15 volumio sudo[7832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 17:51:15 volumio sudo[7832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:15 volumio volumio[7643]: info: UPNP Browser: Client initialized successfully Feb 11 17:51:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:15 volumio go-librespot[7834]: go-librespot daemon starting... Feb 11 17:51:15 volumio sudo[7830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 17:51:15 volumio sudo[7830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:15 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 17:51:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 17:51:15 volumio systemd[1]: mpd.service: Consumed 7.242s CPU time. Feb 11 17:51:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 17:51:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 17:51:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 17:51:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 17:51:15 volumio sudo[7830]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:15 volumio go-librespot[7835]: time="2026-02-11T17:51:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:15 volumio go-librespot[7835]: time="2026-02-11T17:51:15+07:00" level=debug msg="app state loaded" Feb 11 17:51:15 volumio go-librespot[7835]: time="2026-02-11T17:51:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:15 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 17:51:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 17:51:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 17:51:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 17:51:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 17:51:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 17:51:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 17:51:15 volumio volumio[7643]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:15 volumio volumio[7643]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 17:51:15 volumio volumio[7643]: info: [1770807075962] CoreMusicLibrary::Adding element Last_100 Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 17:51:15 volumio volumio[7643]: info: [1770807075971] CoreMusicLibrary::Adding element Webradio Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:51:15 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:51:15 volumio volumio[7643]: info: Initializing BBC Radios Feb 11 17:51:16 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 17:51:16 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:16 volumio volumio[7643]: info: Creating Spotify config file Feb 11 17:51:16 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:16 volumio sudo[7850]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 17:51:16 volumio sudo[7850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 17:51:16 volumio sudo[7850]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:16 volumio go-librespot[7835]: time="2026-02-11T17:51:16+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 11 17:51:16 volumio go-librespot[7835]: time="2026-02-11T17:51:16+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 11 17:51:16 volumio go-librespot[7835]: time="2026-02-11T17:51:16+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 11 17:51:16 volumio go-librespot[7835]: time="2026-02-11T17:51:16+07:00" level=info msg="zeroconf server listening on port 34359" Feb 11 17:51:16 volumio go-librespot[7835]: time="2026-02-11T17:51:16+07:00" level=debug msg="obtained new client token: AADmhhgYm484Q8MLDkt4r6tlM7IdVn1vJvhgLQaXurXDMmGxRmLiUmRmoUbyjRoNjWgBivJt5/OnE7iGt36O4v2kk6pon/Lo47fpdYRb/VbFV8oOCuwkvGpjH/2soRp6OCkZboK+4t8S25NqdSs6b+xcX5R8xOkIpBqB7eqFuCzFzO/8Y/Kc50xOl3cs+XZPAA7PiKZl6JCiaALqfRDnMVF0m/YI7Qz56KRFNu1G1lum28LnXMRBN7ifdg==" Feb 11 17:51:16 volumio go-librespot[7835]: time="2026-02-11T17:51:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:17 volumio go-librespot[7835]: time="2026-02-11T17:51:17+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:17 volumio go-librespot[7835]: time="2026-02-11T17:51:17+07:00" level=debug msg="completed challenge" Feb 11 17:51:17 volumio go-librespot[7835]: time="2026-02-11T17:51:17+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 11 17:51:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:17 volumio volumio[7766]: Starting albumart workers Feb 11 17:51:17 volumio volumio[7767]: Starting albumart workers Feb 11 17:51:17 volumio volumio[7643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 17:51:17 volumio volumio[7643]: info: [1770807077617] CoreMusicLibrary::Adding element YouTube Music Feb 11 17:51:17 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:51:17 volumio volumio[7643]: Cannot find translation for source YouTube Music Feb 11 17:51:17 volumio volumio[7643]: info: Volumio Calling Home Feb 11 17:51:17 volumio sudo[7871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 11 17:51:17 volumio sudo[7871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:17 volumio sudo[7871]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:18 volumio volumio[7768]: Starting albumart workers Feb 11 17:51:18 volumio volumio[7643]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 11 17:51:18 volumio volumio[7643]: info: Discovery: Found device Volumio Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::volumioGetState Feb 11 17:51:18 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:18 volumio volumio[7643]: info: MPD Permissions set Feb 11 17:51:18 volumio volumio[7643]: info: MPD Permissions set Feb 11 17:51:18 volumio volumio[7643]: info: Upmpdcli Daemon Started Feb 11 17:51:18 volumio volumio[7643]: info: Spotify config file written Feb 11 17:51:18 volumio volumio[7643]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio sudo[7877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 11 17:51:18 volumio sudo[7877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 17:51:18 volumio volumio[7643]: info: No need to fix Spotify hosts Feb 11 17:51:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:18 volumio go-librespot[7889]: go-librespot daemon starting... Feb 11 17:51:18 volumio sudo[7877]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:18 volumio go-librespot[7890]: time="2026-02-11T17:51:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:18 volumio go-librespot[7890]: time="2026-02-11T17:51:18+07:00" level=debug msg="app state loaded" Feb 11 17:51:18 volumio go-librespot[7890]: time="2026-02-11T17:51:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:19 volumio volumio[7643]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 11 17:51:19 volumio volumio[7643]: info: Discovery: Found device Volumio Feb 11 17:51:19 volumio volumio[7643]: info: CoreCommandRouter::volumioGetState Feb 11 17:51:19 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:19 volumio go-librespot[7890]: time="2026-02-11T17:51: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 17:51:19 volumio go-librespot[7890]: time="2026-02-11T17:51:19+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 11 17:51:19 volumio go-librespot[7890]: time="2026-02-11T17:51:19+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 11 17:51:19 volumio go-librespot[7890]: time="2026-02-11T17:51:19+07:00" level=info msg="zeroconf server listening on port 43237" Feb 11 17:51:19 volumio go-librespot[7890]: time="2026-02-11T17:51:19+07:00" level=debug msg="obtained new client token: AAB132iuAsI/ziGyQR8bDAH+FdV7t1Pz7i2iiroXQrK35sjC0cob5oBwryUwDd5rhy5Jv4EjQHSwBYIJ2x2s/DCCGfyDUzRe29++FGu/u9jwGXJLzqz4o1NHDqphv60pHBVeVhzdJqwoy8YefjrcCcNK910Tp9XQ23JLlpCh6IJOqdc1PNTy6qkXRPYcqXIQnze2lPKBUl2+w95CED5n/eX6DFJY2kg9U10iSAdpj1MV8xPakMcx4xq9GA==" Feb 11 17:51:20 volumio go-librespot[7890]: time="2026-02-11T17:51:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:20 volumio go-librespot[7890]: time="2026-02-11T17:51:20+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.23:36128->104.199.241.202:4070: read: connection reset by peer" Feb 11 17:51:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:20 volumio volumio[7643]: info: Volumio called home Feb 11 17:51:20 volumio volumio[7643]: info: Starting Shairport Sync Feb 11 17:51:20 volumio volumio[7643]: info: Starting Shairport Sync Feb 11 17:51:20 volumio volumio[7643]: info: Starting Shairport Sync Feb 11 17:51:20 volumio sudo[7917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 17:51:20 volumio sudo[7917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:20 volumio sudo[7920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 17:51:20 volumio sudo[7920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:20 volumio volumio-remote-updater[643]: [2026-02-11 17:51:20] [connect] Successful connection Feb 11 17:51:20 volumio sudo[7922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 17:51:20 volumio sudo[7922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:20 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 17:51:20 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 17:51:20 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 17:51:20 volumio systemd[1]: shairport-sync.service: Consumed 3.879s CPU time. Feb 11 17:51:20 volumio volumio-remote-updater[643]: [2026-02-11 17:51:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770807080 101 Feb 11 17:51:20 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 17:51:20 volumio sudo[7920]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:20 volumio sudo[7917]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:20 volumio sudo[7922]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:20 volumio volumio[7643]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Feb 11 17:51:20 volumio volumio[7643]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 11 17:51:20 volumio volumio[7643]: SPOTIFY: BQAkGRbGtl2IfwVhSg7fi2bIyyhqjkDa7wXjLUNvNVdOqtw8jQ_x7e4CcABJQZ-nUIXO0b06Zl-g2_ElQCCIFZ6nEHCqu4AKoD9inZbdS8XxJtvH20cGk58tH3TjDQvMFaaoS74F9F5VYlZr8-Q4oHO_wBb8hw3Szt_f_EGDmYt9gJXgJKoTiSFbdDwdn_WdpTjH7Go4sU5T9oieprnzMRyTPfD4JFqvddf2Uz1pdHEOskBfScfsDHQjbznLrxmxUcVWCysOuB52b0CiKq5RA_LJMBi-lOcJiQ076_LbZdmLmbCbkTmqvp21 Feb 11 17:51:20 volumio volumio[7643]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 11 17:51:20 volumio volumio[7643]: info: New Spotify access token = BQAkGRbGtl2IfwVhSg7fi2bIyyhqjkDa7wXjLUNvNVdOqtw8jQ_x7e4CcABJQZ-nUIXO0b06Zl-g2_ElQCCIFZ6nEHCqu4AKoD9inZbdS8XxJtvH20cGk58tH3TjDQvMFaaoS74F9F5VYlZr8-Q4oHO_wBb8hw3Szt_f_EGDmYt9gJXgJKoTiSFbdDwdn_WdpTjH7Go4sU5T9oieprnzMRyTPfD4JFqvddf2Uz1pdHEOskBfScfsDHQjbznLrxmxUcVWCysOuB52b0CiKq5RA_LJMBi-lOcJiQ076_LbZdmLmbCbkTmqvp21 Feb 11 17:51:20 volumio volumio[7643]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 11 17:51:20 volumio volumio[7643]: info: Shairport-Sync Started Feb 11 17:51:20 volumio volumio[7643]: Error adding Membership: Error: addMembership EINVAL Feb 11 17:51:20 volumio volumio[7643]: info: Shairport-Sync Started Feb 11 17:51:20 volumio volumio[7643]: info: Shairport-Sync Started Feb 11 17:51:20 volumio volumio[7643]: info: CoreCommandRouter::volumioGetState Feb 11 17:51:20 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:21 volumio volumio[7643]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 11 17:51:21 volumio volumio[7643]: info: Spotify Successfully logged in Feb 11 17:51:21 volumio volumio[7643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 17:51:21 volumio volumio[7643]: info: [1770807081349] CoreMusicLibrary::Adding element Spotify Feb 11 17:51:21 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 17:51:21 volumio volumio[7643]: Cannot find translation for source YouTube Music Feb 11 17:51:21 volumio volumio[7643]: Cannot find translation for source Spotify Feb 11 17:51:21 volumio volumio[7643]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 11 17:51:21 volumio volumio[7643]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 17:51:22 volumio volumio[7643]: info: VolumeController:: Volume=100 Mute =false Feb 11 17:51:22 volumio volumio[7643]: info: CoreCommandRouter::volumioGetState Feb 11 17:51:22 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:22 volumio volumio[7643]: info: CoreStateMachine::pushState Feb 11 17:51:22 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:22 volumio volumio[7643]: info: CoreCommandRouter::volumioPushState Feb 11 17:51:23 volumio mpd[7869]: 2026-02-11T17:51:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 17:51:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 11 17:51:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 17:51:23 volumio sudo[7822]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:23 volumio sudo[7832]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:23 volumio volumio[7643]: info: go-librespot daemon successfully initialized Feb 11 17:51:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:23 volumio go-librespot[7960]: go-librespot daemon starting... Feb 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+07:00" level=debug msg="app state loaded" Feb 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:23 volumio volumio[7643]: error: MPD error: The expression evaluated to a falsy value: Feb 11 17:51:23 volumio volumio[7643]: assert.ok(self.idling) Feb 11 17:51:23 volumio volumio[7643]: error: The expression evaluated to a falsy value: Feb 11 17:51:23 volumio volumio[7643]: assert.ok(self.idling) Feb 11 17:51:23 volumio volumio[7643]: info: MPD running with PID7869 Feb 11 17:51:23 volumio volumio[7643]: ,establishing connection Feb 11 17:51:23 volumio volumio[7643]: error: updateQueue error: null Feb 11 17:51:23 volumio volumio[7643]: info: Completed starting Core Plugins Feb 11 17:51:23 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:51:23 volumio volumio[7643]: info: ----- MyVolumio plugins startup ---- Feb 11 17:51:23 volumio volumio[7643]: info: ------------------------------------------- Feb 11 17:51:23 volumio volumio[7643]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 17:51:23 volumio volumio[7643]: error: updateQueue error: null Feb 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+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 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+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 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+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 11 17:51:23 volumio go-librespot[7962]: time="2026-02-11T17:51:23+07:00" level=info msg="zeroconf server listening on port 37235" Feb 11 17:51:24 volumio go-librespot[7962]: time="2026-02-11T17:51:24+07:00" level=debug msg="obtained new client token: AABsB/CfztYAZslKjSn9TKqdYUOJO8zi5ojxtCQYZ+6N7i8u2RrZYdzaq+YhWvYxX5ZLdliDnK8optR+xkD1dYT86otR6jzay/ohW/+vkVEz/PsMvvQOgmhoOh0eQbTS5msrau+XzR7W37N8b+jzL60Li6LPY6vm5rQsPqE4fAhJ7WiWjvF9DaiA3RqV/eLV1adtwdn2YNzOSME9oH7NBz/Qs3y4CWgL37f2VYRzYWq0WY9QSi5QH8M=" Feb 11 17:51:24 volumio go-librespot[7962]: time="2026-02-11T17:51:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:24 volumio go-librespot[7962]: time="2026-02-11T17:51:24+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:24 volumio go-librespot[7962]: time="2026-02-11T17:51:24+07:00" level=debug msg="completed challenge" Feb 11 17:51:24 volumio go-librespot[7962]: time="2026-02-11T17:51:24+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 11 17:51:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:26 volumio volumio[7643]: info: Initializing connection to go-librespot Websocket Feb 11 17:51:26 volumio volumio[7643]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 17:51:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Feb 11 17:51:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:27 volumio go-librespot[7969]: go-librespot daemon starting... Feb 11 17:51:27 volumio go-librespot[7970]: time="2026-02-11T17:51:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:27 volumio go-librespot[7970]: time="2026-02-11T17:51:27+07:00" level=debug msg="app state loaded" Feb 11 17:51:27 volumio go-librespot[7970]: time="2026-02-11T17:51:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+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 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+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 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+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 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+07:00" level=info msg="zeroconf server listening on port 35315" Feb 11 17:51:28 volumio volumio[7643]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+07:00" level=debug msg="obtained new client token: AACdoWNmBFo6mlZnvVJlNqmUxSMCs0ZRAoBZs17Fmu8v7NZZeEll1OVlL/e/tRQrXViIe/MTggwO+l6J/qUwrUb8t1ASMSnofAFweFzxN/pFiA2Sn8DetvsNvL+SSTFYglnByDpxr9xRPAwS1nvtQ1DxHfv9EF+7s8aa8MPmlQh6YeCOehMNS45wOQ+srwUk+uBXbzQqlIzO99uKAwwrQFrCk2tiqdlfazr4i0DzrhfVmFLObTS50uqxvw==" Feb 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+07:00" level=debug msg="completed challenge" Feb 11 17:51:28 volumio go-librespot[7970]: time="2026-02-11T17:51:28+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 11 17:51:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:29 volumio volumio[7643]: info: Initializing connection to go-librespot Websocket Feb 11 17:51:29 volumio volumio[7643]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 17:51:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Feb 11 17:51:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:31 volumio go-librespot[7978]: go-librespot daemon starting... Feb 11 17:51:31 volumio go-librespot[7979]: time="2026-02-11T17:51:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:31 volumio go-librespot[7979]: time="2026-02-11T17:51:31+07:00" level=debug msg="app state loaded" Feb 11 17:51:31 volumio go-librespot[7979]: time="2026-02-11T17:51:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:32 volumio volumio[7643]: info: Initializing connection to go-librespot Websocket Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+07:00" level=debug msg="new websocket client" Feb 11 17:51:32 volumio volumio[7643]: info: Connection to go-librespot Websocket established Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+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 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+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 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+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 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 17:51:32 volumio volumio[7643]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+07:00" level=info msg="zeroconf server listening on port 39887" Feb 11 17:51:32 volumio volumio[7643]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+07:00" level=debug msg="obtained new client token: AAC5h1J7Rgs0xdSNQVdpkUD++184jJ0OmukL3oZlmE/I+tdL48Sg6wBzct1LGRPdpAig3P6yTyPEmU/j4s3FpaeunUncYltAwyEdemx5KGAkTDuamzdoQ+uKTh0RcbPQpJfENOfTOlyN0U1+zftG73KhRRYIYymD0g++Jzxxw4I3/Aa0LxsNxESx53ULWiIZnytfH8xgGJf/KKmg6s1NKcgo2+eTa+8NhNfKcw3DU5d+qjyLef6twOgxTA==" Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+07:00" level=debug msg="completed challenge" Feb 11 17:51:32 volumio go-librespot[7979]: time="2026-02-11T17:51:32+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 11 17:51:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:34 volumio volumio[7643]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 17:51:34 volumio volumio[7643]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 17:51:34 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:34 volumio volumio[7643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 17:51:34 volumio volumio[7643]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 17:51:34 volumio volumio[7643]: info: MyVolumio login type: Token Feb 11 17:51:34 volumio volumio[7643]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 17:51:34 volumio volumio[7643]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 17:51:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Feb 11 17:51:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 17:51:36 volumio go-librespot[8000]: go-librespot daemon starting... Feb 11 17:51:36 volumio volumio[7643]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 17:51:36 volumio volumio[7643]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 17:51:36 volumio volumio[7643]: info: Streaming services startup Feb 11 17:51:36 volumio volumio[7643]: info: Starting Streaming Daemon Feb 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 17:51:36 volumio sudo[8010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 17:51:36 volumio sudo[8010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51:36+07:00" level=debug msg="app state loaded" Feb 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 17:51:36 volumio volumio[7643]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 17:51:36 volumio sudo[8010]: pam_unix(sudo:session): session closed for user root Feb 11 17:51:36 volumio volumio[7643]: info: Getting Spotify volume Feb 11 17:51:36 volumio volumio[7643]: info: Connection to go-librespot Websocket closed Feb 11 17:51:36 volumio volumio[7643]: error: Cannot start Volumio Streaming Daemon Feb 11 17:51:36 volumio volumio[7643]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 17:51:36 volumio volumio[7643]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 17:51:36 volumio volumio[7643]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 11 17:51:36 volumio volumio[7643]: info: CoreCommandRouter::volumioGetState Feb 11 17:51:36 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:36 volumio volumio[7643]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 11 17:51:36 volumio volumio[7643]: SPOTIFY: SPOTIFY VOLUME undefined Feb 11 17:51:36 volumio volumio[7643]: SPOTIFY: VOLUMIO VOLUME 100 Feb 11 17:51:36 volumio volumio[7643]: info: Aligning Spotify Volume to Volumio Volume Feb 11 17:51:36 volumio volumio[7643]: info: CoreCommandRouter::volumioGetState Feb 11 17:51:36 volumio volumio[7643]: info: CorePlayQueue::getTrack 0 Feb 11 17:51:36 volumio volumio[7643]: info: Setting Spotify Volume from Volumio: 100 Feb 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51: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-gew4.spotify.com:80]" Feb 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51:36+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 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51:36+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 11 17:51:36 volumio go-librespot[8001]: time="2026-02-11T17:51:36+07:00" level=info msg="zeroconf server listening on port 45429" Feb 11 17:51:37 volumio go-librespot[8001]: time="2026-02-11T17:51:37+07:00" level=debug msg="obtained new client token: AABdhN+OLeocuGuXenxf9htlpZduXn2Clxbvn2k+5xsXzxI4DmrUlfzfOLjlHO1zuRwAi8Q7LqMa0zVwZcdPjWVPJrB2MsNvArqhPYeU0B6jwD10iN+Z485I9vR9CdqWsRqm+AUkYQnlmLuJzS5hNpyDMu7y/Yjzzr9sDbXl4favj5RNQadXrJwDflD+6CGQshU9Sf6E+r8rtOjPksjFeWPsVeOdGhq1ugaGWJjWFl9oiin0dGkf0FBCdQ==" Feb 11 17:51:37 volumio go-librespot[8001]: time="2026-02-11T17:51:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 17:51:37 volumio go-librespot[8001]: time="2026-02-11T17:51:37+07:00" level=debug msg="completed keyexchange" Feb 11 17:51:37 volumio go-librespot[8001]: time="2026-02-11T17:51:37+07:00" level=debug msg="completed challenge" Feb 11 17:51:37 volumio go-librespot[8001]: time="2026-02-11T17:51: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 11 17:51:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 17:51:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 17:51:37 volumio volumio[7643]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:51:37 volumio volumio[7643]: Error: socket hang up Feb 11 17:51:37 volumio volumio[7643]: at connResetException (node:internal/errors:720:14) Feb 11 17:51:37 volumio volumio[7643]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 11 17:51:37 volumio volumio[7643]: at Socket.emit (node:events:526:35) Feb 11 17:51:37 volumio volumio[7643]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 11 17:51:37 volumio volumio[7643]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 11 17:51:37 volumio volumio[7643]: code: 'ECONNRESET', Feb 11 17:51:37 volumio volumio[7643]: response: undefined Feb 11 17:51:37 volumio volumio[7643]: } Feb 11 17:51:37 volumio volumio[7643]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 17:51:38 volumio sudo[8030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 17:50' Feb 11 17:51:38 volumio sudo[8030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"