Feb 18 07:59:00 volumio volumio[10921]: info: Getting Spotify volume Feb 18 07:59:00 volumio volumio[10921]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 07:59:00 volumio volumio[10921]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 07:59:00 volumio volumio[10921]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 07:59:00 volumio volumio[10921]: errno: -111, Feb 18 07:59:00 volumio volumio[10921]: code: 'ECONNREFUSED', Feb 18 07:59:00 volumio volumio[10921]: syscall: 'connect', Feb 18 07:59:00 volumio volumio[10921]: address: '127.0.0.1', Feb 18 07:59:00 volumio volumio[10921]: port: 9879, Feb 18 07:59:00 volumio volumio[10921]: response: undefined Feb 18 07:59:00 volumio volumio[10921]: } Feb 18 07:59:00 volumio volumio[10921]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 07:59:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 18 07:59:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:00 volumio go-librespot[11265]: go-librespot daemon starting... Feb 18 07:59:00 volumio go-librespot[11266]: time="2026-02-18T07:59:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:00 volumio go-librespot[11266]: time="2026-02-18T07:59:00+07:00" level=debug msg="app state loaded" Feb 18 07:59:00 volumio go-librespot[11266]: time="2026-02-18T07:59:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=info msg="zeroconf server listening on port 43815" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="obtained new client token: AACtjVCj2iPm89waTefFh3azKNCTmlqRDH2cJ4p+bP2z8jH1YchsvGkp/esJpymhDtdeloafKa5FBZy7+gSaQlejO6U3SK7dOCYO3TMbG6b58iBsVHagrd5mFn81ZjMM1TNlNmMhE2/3vfXfVRs4qbip9NNmDlIntDOoXZf+gJhuYDqbaihatAOGG9Y0CiGm89wN7ZjGno6eHhrGFkLZy16SJ4vnonXEeAzP8ilHrQ7PUMeFYR5BM6R+7A==" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=debug msg="completed challenge" Feb 18 07:59:01 volumio go-librespot[11266]: time="2026-02-18T07:59:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:01 volumio sudo[11289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 07:58' Feb 18 07:59:01 volumio sudo[11289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:02 volumio sudo[11289]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 18 07:59:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:05 volumio go-librespot[11297]: go-librespot daemon starting... Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=debug msg="app state loaded" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=info msg="zeroconf server listening on port 45667" Feb 18 07:59:05 volumio go-librespot[11298]: time="2026-02-18T07:59:05+07:00" level=debug msg="obtained new client token: AABGVltKA3G857EXrvj+5q4hj+KKmBrxXjmTXioNSvoKoQiFAOAKwwO4Qirs9ntA6PY/xUxGtU9G6jYnnyJnHKBrDGXbNjFqTcquF6mpxWm7BQY7ow7iUEJ9ua2OWSLclAcOKWPr1DQc1NIWtw2f8S0XdkaoArcduUt7rvzNfX3a6QWPwwgk//2E1iICp06mmLa83P9pMeQMmt2tzS+m0kJQlJxn5Rn5KuE4QSubaaNVOryoBbdwT/tz2A==" Feb 18 07:59:06 volumio go-librespot[11298]: time="2026-02-18T07:59:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:06 volumio go-librespot[11298]: time="2026-02-18T07:59:06+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:06 volumio go-librespot[11298]: time="2026-02-18T07:59:06+07:00" level=debug msg="completed challenge" Feb 18 07:59:06 volumio go-librespot[11298]: time="2026-02-18T07:59:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 18 07:59:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:09 volumio go-librespot[11305]: go-librespot daemon starting... Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=debug msg="app state loaded" Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:09 volumio go-librespot[11306]: time="2026-02-18T07:59:09+07:00" level=info msg="zeroconf server listening on port 34295" Feb 18 07:59:10 volumio go-librespot[11306]: time="2026-02-18T07:59:10+07:00" level=debug msg="obtained new client token: AAA5eNbU58AmptX89D5VOcRzGL8HezLQNe7bbVS+/Imap3+AEOkdFUnC6gMnNUmaWRrbJhvofKdR7IHkYsoDMyjPlDVXOIgfKGQtldZu+pBZ8QLzBfsUxB21jSAfLyPdF19/5CzVNnXTmdyaV7DueQLEu1xB2uIJtnDOi8jQDRxFUlILHRprhwCFMQMWvXdGyOjil4En455J1lB1lGxd6CHif17fqUevbrdijCQURCwgRMDOtCtE5BM=" Feb 18 07:59:10 volumio go-librespot[11306]: time="2026-02-18T07:59:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:10 volumio go-librespot[11306]: time="2026-02-18T07:59:10+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:10 volumio go-librespot[11306]: time="2026-02-18T07:59:10+07:00" level=debug msg="completed challenge" Feb 18 07:59:10 volumio go-librespot[11306]: time="2026-02-18T07:59:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:12 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:12] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 18 07:59:12 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:12] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 18 07:59:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 07:59:12 volumio systemd[1]: volumio.service: Consumed 49.673s CPU time. Feb 18 07:59:13 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 07:59:13 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 07:59:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21608. Feb 18 07:59:13 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 07:59:13 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 07:59:13 volumio systemd[1]: volumio.service: Consumed 49.673s CPU time. Feb 18 07:59:13 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 07:59:13 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 07:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 18 07:59:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:13 volumio go-librespot[11355]: go-librespot daemon starting... Feb 18 07:59:13 volumio go-librespot[11356]: time="2026-02-18T07:59:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:13 volumio go-librespot[11356]: time="2026-02-18T07:59:13+07:00" level=debug msg="app state loaded" Feb 18 07:59:13 volumio go-librespot[11356]: time="2026-02-18T07:59:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=info msg="zeroconf server listening on port 41443" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="obtained new client token: AAB2a0EuSmXHCg7hoBYyyDEuCZcyPM/qBREX+Y9gvtidMr4agzqcUXvShT1ft9CBn4g7rO2XUWvtqLwwPEj5sDcHZDBaXtUetLQtPJiwadSCz0UzCMTO3+QhPN5t9H5Q/e4S40Q3hGOPpzSITEqddvYAunR0vc/wqR8C7u4scv9NkEZF3Z0/QUYaNJOHVwa6G69zA6VKx+rkg1Ecn/BZ7KfKAVlL2kcwPwu3AL77lMhwWT1rgD9v1O1h2Q==" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=debug msg="completed challenge" Feb 18 07:59:14 volumio go-librespot[11356]: time="2026-02-18T07:59:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:16 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:16 volumio volumio[11340]: info: ----- Volumio3 ---- Feb 18 07:59:16 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:16 volumio volumio[11340]: info: ----- System startup ---- Feb 18 07:59:16 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:17 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:17] [connect] Successful connection Feb 18 07:59:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 18 07:59:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:18 volumio volumio[11340]: info: MYVOLUMIO Environment detected Feb 18 07:59:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:18 volumio go-librespot[11371]: go-librespot daemon starting... Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=debug msg="app state loaded" Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:18 volumio volumio[11340]: info: Plugin folders cleanup Feb 18 07:59:18 volumio volumio[11340]: info: Scanning into folder /volumio/app/plugins/ Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category audio_interface Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category miscellanea Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category music_service Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category plugins.json Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category system_controller Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category user_interface Feb 18 07:59:18 volumio volumio[11340]: info: Scanning into folder /data/plugins/ Feb 18 07:59:18 volumio volumio[11340]: info: Scanning category music_service Feb 18 07:59:18 volumio volumio[11340]: info: Plugin folders cleanup completed Feb 18 07:59:18 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:18 volumio volumio[11340]: info: ----- Core plugins startup ---- Feb 18 07:59:18 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:18 volumio volumio[11340]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 07:59:18 volumio volumio[11340]: info: Adding plugin upnp to MyMusic Plugins Feb 18 07:59:18 volumio volumio[11340]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 07:59:18 volumio volumio[11340]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 07:59:18 volumio volumio[11340]: info: Loading plugins from folder /data/plugins/ Feb 18 07:59:18 volumio volumio[11340]: info: Loading plugin "system"... Feb 18 07:59:18 volumio volumio[11340]: info: Loading plugin "appearance"... Feb 18 07:59:18 volumio nmbd[966]: [2026/02/18 07:59:18.525373, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 18 07:59:18 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 18 07:59:18 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=info msg="zeroconf server listening on port 37837" Feb 18 07:59:18 volumio go-librespot[11373]: time="2026-02-18T07:59:18+07:00" level=debug msg="obtained new client token: AACCy2+UquHkoIvu0RQZJgEmWA/qQnM/jgJs0beq1dYOA9nKSLRTb/y8qQwzX+9KXZjWbVE6AY5pYmhf6b3Ot981mYTNg1/xxPoC9AgEvGHph7GrBNvYGn12AdChnjpII93S+vuhPpfjGZzL/ywvwajUoBuheG1sl5YBTw4v3yzlyvKbNzFqDeb31fmtZwZ3+FnmW/8Z9N1AIOU3/f5bAGafKrfb5IsFaFTNdCcJ3DvAJb2OOipLDeQAYQ==" Feb 18 07:59:19 volumio go-librespot[11373]: time="2026-02-18T07:59:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:19 volumio go-librespot[11373]: time="2026-02-18T07:59:19+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:19 volumio go-librespot[11373]: time="2026-02-18T07:59:19+07:00" level=debug msg="completed challenge" Feb 18 07:59:19 volumio go-librespot[11373]: time="2026-02-18T07:59:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "network"... Feb 18 07:59:20 volumio volumio[11340]: info: Refreshing Cached IP Addresses Feb 18 07:59:20 volumio sudo[11385]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 07:59:20 volumio sudo[11385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "services"... Feb 18 07:59:20 volumio sudo[11387]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 07:59:20 volumio sudo[11387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:20 volumio sudo[11385]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "alsa_controller"... Feb 18 07:59:20 volumio sudo[11387]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:20 volumio sudo[11396]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 07:59:20 volumio sudo[11396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:20 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "wizard"... Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "networkfs"... Feb 18 07:59:20 volumio volumio[11340]: info: Starting Udev Watcher for removable devices Feb 18 07:59:20 volumio volumio[11340]: info: Ignoring mount for partition: boot Feb 18 07:59:20 volumio volumio[11340]: info: Ignoring mount for partition: volumio Feb 18 07:59:20 volumio volumio[11340]: info: Ignoring mount for partition: volumio_data Feb 18 07:59:20 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "volumio_command_line_client"... Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "upnp"... Feb 18 07:59:20 volumio volumio[11340]: info: [1771376360680] Starting Upmpd Daemon Feb 18 07:59:20 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "my_music"... Feb 18 07:59:20 volumio volumio[11340]: info: Loading plugin "mpd"... Feb 18 07:59:21 volumio volumio[11340]: info: Loading plugin "upnp_browser"... Feb 18 07:59:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 18 07:59:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:22 volumio go-librespot[11418]: go-librespot daemon starting... Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=debug msg="app state loaded" Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:22 volumio go-librespot[11419]: time="2026-02-18T07:59:22+07:00" level=info msg="zeroconf server listening on port 35533" Feb 18 07:59:23 volumio go-librespot[11419]: time="2026-02-18T07:59:23+07:00" level=debug msg="obtained new client token: AABPAHmIlrgH5GuwrZ+1H+Sf4yyTH6vw2YMTlfj2g9L72w77+2QhMKMh6OU1ODrJ6AselpgEPz/TRPEHwSsfgMPi14jA5V824ZM5LRTnFd6g+HXM+2bd444iJA95YWmRD7+gQ1IT3zeEtTvq77quNiOYBzsmWivYTJ4IdV54LWx8zHHF+uRbpgn5h+PSwC15wr1ILoPHir6GEHmG/DCrf4hEq1YmFcDS15iqVCjxQIdUi3ctShAdlo4=" Feb 18 07:59:23 volumio sudo[11396]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:23 volumio go-librespot[11419]: time="2026-02-18T07:59:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:23 volumio go-librespot[11419]: time="2026-02-18T07:59:23+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:23 volumio go-librespot[11419]: time="2026-02-18T07:59:23+07:00" level=debug msg="completed challenge" Feb 18 07:59:23 volumio go-librespot[11419]: time="2026-02-18T07:59:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:24 volumio volumio[11340]: info: Starting UPNP Browser Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "alarm-clock"... Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "airplay_emulation"... Feb 18 07:59:24 volumio volumio[11340]: info: Starting Shairport Sync Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "last_100"... Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "webradio"... Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "i2s_dacs"... Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "volumiodiscovery"... Feb 18 07:59:24 volumio volumio[11340]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 07:59:24 volumio volumio[11340]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 07:59:24 volumio node[11340]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 07:59:24 volumio volumio[11340]: *** WARNING *** For more information see Feb 18 07:59:24 volumio volumio[11340]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 07:59:24 volumio volumio[11340]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 07:59:24 volumio volumio[11340]: *** WARNING *** For more information see Feb 18 07:59:24 volumio node[11340]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 07:59:24 volumio node[11340]: *** WARNING *** For more information see Feb 18 07:59:24 volumio node[11340]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 07:59:24 volumio node[11340]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 07:59:24 volumio node[11340]: *** WARNING *** For more information see Feb 18 07:59:24 volumio volumio[11340]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 07:59:24 volumio volumio[11340]: info: Discovery: Started advertising with name: Volumio Feb 18 07:59:24 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 07:59:24 volumio volumio[11340]: info: Loading plugin "spop"... Feb 18 07:59:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 18 07:59:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:26 volumio go-librespot[11428]: go-librespot daemon starting... Feb 18 07:59:26 volumio go-librespot[11429]: time="2026-02-18T07:59:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:26 volumio go-librespot[11429]: time="2026-02-18T07:59:26+07:00" level=debug msg="app state loaded" Feb 18 07:59:26 volumio go-librespot[11429]: time="2026-02-18T07:59:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:26 volumio volumio[11340]: info: Loading plugin "ytcr"... Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=info msg="zeroconf server listening on port 39949" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="obtained new client token: AAD186nuzbnxqumwH5Jz0NxmzIbPv0AkMWNGAY9cYC1Bw4QK6WAZbeApLMaTqKbez2Q57vXipBwGDzytz7uwbxnlCjL+2zd1cQ+XIm8YyMLTmUdQSKTHVVNVysDcnla295xGii21fIa7Q1JqmVy5Dff6nEQTa4by7cZtz1nABgOiV0IhnAD1iGxF13qimq45QjRNx0Gs2VlJBbhfgksdVwWdPL0IS0GGBd0rGa+OrjdMwdZuK57YakAUeg==" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=debug msg="completed challenge" Feb 18 07:59:27 volumio go-librespot[11429]: time="2026-02-18T07:59:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:29 volumio volumio[11340]: info: Loading plugin "ytmusic"... Feb 18 07:59:30 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:30] [connect] Successful connection Feb 18 07:59:30 volumio volumio[11340]: info: Loading plugin "outputs"... Feb 18 07:59:30 volumio volumio[11340]: info: Loading plugin "albumart"... Feb 18 07:59:30 volumio volumio[11340]: info: Plugin example_plugin is not enabled Feb 18 07:59:30 volumio volumio[11340]: info: Loading plugin "inputs"... Feb 18 07:59:30 volumio volumio[11340]: info: Loading plugin "updater_comm"... Feb 18 07:59:30 volumio volumio[11340]: info: Plugin mpdemulation is not enabled Feb 18 07:59:30 volumio volumio[11340]: info: Loading plugin "rest_api"... Feb 18 07:59:31 volumio volumio[11340]: info: Loading plugin "websocket"... Feb 18 07:59:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 18 07:59:31 volumio volumio[11340]: info: Starting Socket.io Server version 1.7.4 Feb 18 07:59:31 volumio volumio[11340]: info: Loading plugin "RoonBridge"... Feb 18 07:59:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:31 volumio go-librespot[11461]: go-librespot daemon starting... Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=debug msg="app state loaded" Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:31 volumio volumio[11340]: info: Applying required configuration parameters for plugin RoonBridge Feb 18 07:59:31 volumio volumio[11340]: info: Loading i18n strings for locale en Feb 18 07:59:31 volumio volumio[11340]: Updating browse sources language Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 07:59:31 volumio volumio[11451]: Forking 3 albumart workers Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=info msg="zeroconf server listening on port 42865" Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::initPlayerControls Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:31 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 07:59:31 volumio go-librespot[11462]: time="2026-02-18T07:59:31+07:00" level=debug msg="obtained new client token: AACgmkw+yJ69gXyw+SAoTr0D98v+c+OPVk7CZKHjBpiFd8rHO+aDqQ0DGYY0YyD3e2tb+0fGlFIzFVljnps20gGfgMG6VWOHtt+ZckX4HC1ZqKRvcQb+mZVE3DTTOYoJU67Ss3XZ+DmHBaKPN6ewzKS2OfGdS8f2VuoJuFL7Mv6/4lr0N4WTNsADm83iiIKd4bkr9ROMz5vOTUR9U5/C2H/1e26EGqwko/bkUyOHRRGa1TH8/9MidIVgZw==" Feb 18 07:59:31 volumio volumio[11340]: Express server listening on port 3000 Feb 18 07:59:32 volumio volumio[11340]: [Metrics] WebUI: 16s 189.39ms Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::resetVolumioState Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::getcurrentVolume Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 07:59:32 volumio go-librespot[11462]: time="2026-02-18T07:59:32+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 07:59:32 volumio go-librespot[11462]: time="2026-02-18T07:59:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 07:59:32 volumio sudo[11504]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 07:59:32 volumio sudo[11504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:32 volumio sudo[11506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 07:59:32 volumio go-librespot[11462]: time="2026-02-18T07:59:32+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:32 volumio go-librespot[11462]: time="2026-02-18T07:59:32+07:00" level=debug msg="completed challenge" Feb 18 07:59:32 volumio sudo[11506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:32 volumio sudo[11504]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:32 volumio sudo[11506]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:32 volumio volumio[11340]: info: Volumio Network Manager: Network status updated: 1 Feb 18 07:59:32 volumio go-librespot[11462]: time="2026-02-18T07:59: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 18 07:59:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:32 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771376370 101 Feb 18 07:59:32 volumio volumio[11340]: 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: 1 Feb 18 07:59:32 volumio volumio[11340]: info: VolumeController:: Volume=100 Mute =false Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::pushState Feb 18 07:59:32 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::volumioPushState Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::updateTrackBlock Feb 18 07:59:32 volumio volumio[11340]: info: CorePlayQueue::getTrackBlock Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:32 volumio volumio[11340]: info: Reloading queue from file Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::setRepeat null single undefined Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::pushState Feb 18 07:59:32 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 07:59:32 volumio volumio[11340]: info: CoreCommandRouter::volumioPushState Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::setRandom null Feb 18 07:59:32 volumio volumio[11340]: info: CoreStateMachine::pushState Feb 18 07:59:32 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::volumioPushState Feb 18 07:59:33 volumio volumio[11340]: info: Setting Device type: Raspberry PI Feb 18 07:59:33 volumio volumio[11340]: info: Completed loading Core Plugins Feb 18 07:59:33 volumio volumio[11340]: info: Preparing to generate the ALSA configuration file Feb 18 07:59:33 volumio volumio[11340]: info: VolumeController:: Volume=100 Mute =false Feb 18 07:59:33 volumio volumio[11340]: info: CoreStateMachine::pushState Feb 18 07:59:33 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::volumioPushState Feb 18 07:59:33 volumio sudo[11523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 07:59:33 volumio sudo[11523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:33 volumio volumio[11340]: info: Asound.conf file unchanged, so no further update is needed Feb 18 07:59:33 volumio volumio[11340]: info: Output device has changed, restarting MPD Feb 18 07:59:33 volumio sudo[11523]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:33 volumio volumio[11340]: info: Output device has changed, restarting Shairport Sync Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:33 volumio sudo[11526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 07:59:33 volumio sudo[11526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:33 volumio sudo[11526]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:33 volumio sudo[11528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 07:59:33 volumio sudo[11528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:33 volumio volumio[11340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 07:59:33 volumio volumio[11340]: info: ___________ START PLUGINS ___________ Feb 18 07:59:33 volumio volumio[11340]: info: ControllerMpd::onStart: Initializing MPD Feb 18 07:59:33 volumio volumio[11340]: info: Creating MPD Configuration file Feb 18 07:59:33 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 07:59:33 volumio volumio[11340]: info: [1771376373622] CoreMusicLibrary::Adding element Media Servers Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 07:59:33 volumio sudo[11536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 07:59:33 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 07:59:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 07:59:33 volumio systemd[1]: mpd.service: Consumed 7.096s CPU time. Feb 18 07:59:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 07:59:33 volumio sudo[11536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 07:59:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 07:59:33 volumio volumio[11340]: info: UPNP Browser: Client initialized successfully Feb 18 07:59:33 volumio sudo[11536]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:33 volumio sudo[11538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 07:59:33 volumio sudo[11538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 07:59:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:33 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 07:59:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 07:59:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 07:59:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 07:59:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 07:59:33 volumio volumio[11340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:33 volumio volumio[11340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 07:59:33 volumio volumio[11340]: info: [1771376373954] CoreMusicLibrary::Adding element Last_100 Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 07:59:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 07:59:33 volumio volumio[11340]: info: [1771376373964] CoreMusicLibrary::Adding element Webradio Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 07:59:33 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 07:59:33 volumio volumio[11340]: info: Initializing BBC Radios Feb 18 07:59:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 07:59:34 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 07:59:34 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:34 volumio volumio[11340]: info: Creating Spotify config file Feb 18 07:59:34 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:34 volumio sudo[11552]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 07:59:34 volumio sudo[11552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 07:59:34 volumio sudo[11552]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:34 volumio volumio[11471]: Starting albumart workers Feb 18 07:59:34 volumio volumio[11470]: Starting albumart workers Feb 18 07:59:35 volumio volumio[11340]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 07:59:35 volumio volumio[11340]: info: [1771376375341] CoreMusicLibrary::Adding element YouTube Music Feb 18 07:59:35 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 07:59:35 volumio volumio[11340]: Cannot find translation for source YouTube Music Feb 18 07:59:35 volumio volumio[11340]: info: Volumio Calling Home Feb 18 07:59:35 volumio sudo[11569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 07:59:35 volumio sudo[11569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:35 volumio volumio[11469]: Starting albumart workers Feb 18 07:59:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 18 07:59:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:35 volumio go-librespot[11574]: go-librespot daemon starting... Feb 18 07:59:35 volumio sudo[11569]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:35 volumio go-librespot[11575]: time="2026-02-18T07:59:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:36 volumio go-librespot[11575]: time="2026-02-18T07:59:36+07:00" level=info msg="zeroconf server listening on port 33753" Feb 18 07:59:36 volumio volumio[11340]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 07:59:36 volumio volumio[11340]: info: Discovery: Found device Volumio Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::volumioGetState Feb 18 07:59:36 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:36 volumio volumio[11340]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 07:59:36 volumio volumio[11340]: info: Discovery: Found device Volumio Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::volumioGetState Feb 18 07:59:36 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:36 volumio volumio[11340]: info: MPD Permissions set Feb 18 07:59:36 volumio volumio[11340]: info: MPD Permissions set Feb 18 07:59:36 volumio volumio[11340]: info: Upmpdcli Daemon Started Feb 18 07:59:36 volumio volumio[11340]: info: Spotify config file written Feb 18 07:59:36 volumio volumio[11340]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio sudo[11596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 07:59:36 volumio sudo[11596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:36 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:37 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 18 07:59:37 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 07:59:37 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 07:59:37 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 18 07:59:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:37 volumio volumio[11340]: info: No need to fix Spotify hosts Feb 18 07:59:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:37 volumio go-librespot[11608]: go-librespot daemon starting... Feb 18 07:59:37 volumio sudo[11596]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=debug msg="app state loaded" Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:37 volumio volumio[11340]: info: Volumio called home Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:37 volumio go-librespot[11609]: time="2026-02-18T07:59:37+07:00" level=info msg="zeroconf server listening on port 38147" Feb 18 07:59:37 volumio volumio[11340]: info: Starting Shairport Sync Feb 18 07:59:37 volumio volumio[11340]: info: Starting Shairport Sync Feb 18 07:59:38 volumio volumio[11340]: info: Starting Shairport Sync Feb 18 07:59:38 volumio sudo[11623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 07:59:38 volumio sudo[11623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:38 volumio sudo[11627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 07:59:38 volumio sudo[11627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:38 volumio sudo[11625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 07:59:38 volumio sudo[11625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:38 volumio go-librespot[11609]: time="2026-02-18T07:59:38+07:00" level=debug msg="obtained new client token: AAC7tn0OYEUZpSMLAjcR3XZIUDteEM8pfD+vKE3H7KsFUbv3CmTyC1WHODFOXdtxcs90XaqmGx2RxtOT3yzA+aYEgk8iDTnZtlX7yhw0TahUM9YR0eVxMOgMT0JMpK8OEf5qzA8ZIDnZZwoxMwKLy3c0fCtnKOuj0D1CCqh8fgxshBEAN1VDzd6s4mFMWXIjTUqRzTXnjy5wHjs+OPgehyZ+KabqE/uRi8ecCcV7DtZLTBZoJJG7cMg=" Feb 18 07:59:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 07:59:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 07:59:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 07:59:38 volumio systemd[1]: shairport-sync.service: Consumed 2.274s CPU time. Feb 18 07:59:38 volumio volumio[11340]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 07:59:38 volumio volumio[11340]: SPOTIFY: BQAZxyjkcx3EJ3iCd6cvCKjT_CMsjkpvZzHLVYdCRHXldvLue77XlEDSULKXMqtZoxCmS9xxv487BNGvHTBH5zEgF7IXIbbqUp9DhgETltEBwp1WIVJDc2-hLSb6HUK42xgojCrkLmHbMwTuoJV6cqqYqXViB5h14fn-fs6KlbxqKwqIiYYdWlYy2vqXW_3rk6AXN8fAwP50-v9erYKYZAIdkZ3Yg2ab984ZlX_8mDG_l6tWeLhhTVclDmh0iQ7bOW-p-gR1K9uE6ZNG3Bw0Jo2ZywZgxr30HxfCemw-1B1ZZ0-cQKiskAAh Feb 18 07:59:38 volumio volumio[11340]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 07:59:38 volumio volumio[11340]: info: New Spotify access token = BQAZxyjkcx3EJ3iCd6cvCKjT_CMsjkpvZzHLVYdCRHXldvLue77XlEDSULKXMqtZoxCmS9xxv487BNGvHTBH5zEgF7IXIbbqUp9DhgETltEBwp1WIVJDc2-hLSb6HUK42xgojCrkLmHbMwTuoJV6cqqYqXViB5h14fn-fs6KlbxqKwqIiYYdWlYy2vqXW_3rk6AXN8fAwP50-v9erYKYZAIdkZ3Yg2ab984ZlX_8mDG_l6tWeLhhTVclDmh0iQ7bOW-p-gR1K9uE6ZNG3Bw0Jo2ZywZgxr30HxfCemw-1B1ZZ0-cQKiskAAh Feb 18 07:59:38 volumio volumio[11340]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 07:59:38 volumio go-librespot[11609]: time="2026-02-18T07:59:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 07:59:38 volumio sudo[11627]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:38 volumio sudo[11623]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 07:59:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 07:59:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 07:59:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 07:59:38 volumio go-librespot[11609]: time="2026-02-18T07:59:38+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:38 volumio sudo[11625]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:38 volumio go-librespot[11609]: time="2026-02-18T07:59:38+07:00" level=debug msg="completed challenge" Feb 18 07:59:38 volumio go-librespot[11609]: time="2026-02-18T07:59:38+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:38 volumio volumio[11340]: info: Shairport-Sync Started Feb 18 07:59:38 volumio volumio[11340]: Error adding Membership: Error: addMembership EINVAL Feb 18 07:59:38 volumio volumio[11340]: info: Shairport-Sync Started Feb 18 07:59:38 volumio volumio[11340]: info: Shairport-Sync Started Feb 18 07:59:38 volumio volumio[11340]: info: CoreCommandRouter::volumioGetState Feb 18 07:59:38 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:38 volumio volumio[11340]: 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 18 07:59:38 volumio volumio[11340]: info: Spotify Successfully logged in Feb 18 07:59:38 volumio volumio[11340]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 07:59:38 volumio volumio[11340]: info: [1771376378996] CoreMusicLibrary::Adding element Spotify Feb 18 07:59:38 volumio volumio[11340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 07:59:39 volumio volumio[11340]: Cannot find translation for source YouTube Music Feb 18 07:59:39 volumio volumio[11340]: Cannot find translation for source Spotify Feb 18 07:59:39 volumio volumio[11340]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 07:59:39 volumio volumio[11340]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 07:59:39 volumio volumio[11340]: info: VolumeController:: Volume=100 Mute =false Feb 18 07:59:39 volumio volumio[11340]: info: CoreCommandRouter::volumioGetState Feb 18 07:59:39 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:39 volumio volumio[11340]: info: CoreStateMachine::pushState Feb 18 07:59:39 volumio volumio[11340]: info: CorePlayQueue::getTrack 0 Feb 18 07:59:39 volumio volumio[11340]: info: CoreCommandRouter::volumioPushState Feb 18 07:59:40 volumio volumio[11340]: info: go-librespot daemon successfully initialized Feb 18 07:59:41 volumio mpd[11567]: 2026-02-18T07:59:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 07:59:41 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 07:59:41 volumio sudo[11528]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:41 volumio sudo[11538]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:41 volumio volumio[11340]: error: MPD error: The expression evaluated to a falsy value: Feb 18 07:59:41 volumio volumio[11340]: assert.ok(self.idling) Feb 18 07:59:41 volumio volumio[11340]: error: The expression evaluated to a falsy value: Feb 18 07:59:41 volumio volumio[11340]: assert.ok(self.idling) Feb 18 07:59:41 volumio volumio[11340]: error: updateQueue error: null Feb 18 07:59:41 volumio volumio[11340]: info: MPD running with PID11567 Feb 18 07:59:41 volumio volumio[11340]: ,establishing connection Feb 18 07:59:41 volumio volumio[11340]: info: Completed starting Core Plugins Feb 18 07:59:41 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:41 volumio volumio[11340]: info: ----- MyVolumio plugins startup ---- Feb 18 07:59:41 volumio volumio[11340]: info: ------------------------------------------- Feb 18 07:59:41 volumio volumio[11340]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 07:59:41 volumio volumio[11340]: error: updateQueue error: null Feb 18 07:59:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 18 07:59:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:41 volumio go-librespot[11669]: go-librespot daemon starting... Feb 18 07:59:41 volumio go-librespot[11670]: time="2026-02-18T07:59:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:41 volumio go-librespot[11670]: time="2026-02-18T07:59:41+07:00" level=debug msg="app state loaded" Feb 18 07:59:41 volumio go-librespot[11670]: time="2026-02-18T07:59:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=info msg="zeroconf server listening on port 38567" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="obtained new client token: AAC5l3cF5YkrHIH4JisBC2yWAHqVm3+qk71Ud2TCdcOA9fqA+sf9ncdN2N8qe+xFaxzZKoldlopjOx7RSQchxBDR00Ol9pK0wgeRpi+d/f2Xy3ApWPsZYX6JLK/FuGKn3NPXv5j7DyHUEmWA7tKLIgci9gic6eBt3PnSEny0nHGo97W7Nrk49TWds8cMqi/9W+ZfuC8pzrXL72JJ1b8kH+/TEriTuCm+NYJF664WfUp2qAEvCYx+2OgOXQ==" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=debug msg="completed challenge" Feb 18 07:59:42 volumio go-librespot[11670]: time="2026-02-18T07:59:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:43 volumio volumio[11340]: info: Initializing connection to go-librespot Websocket Feb 18 07:59:43 volumio volumio[11340]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 07:59:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 18 07:59:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:46 volumio go-librespot[11678]: go-librespot daemon starting... Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="app state loaded" Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:46 volumio volumio[11340]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=info msg="zeroconf server listening on port 41083" Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="obtained new client token: AAAeuOx+TeU0qVW42/UrxSnKSWGXZvezzuwY77ed3GzhxTt3BfxaKth3MFr0v3HqS87fp3sKxJwsfAZ/7Bkte9+uHXPPyQ4hNskGl21rPAyrrKrq9iabvnNzyo8bxt9ARdiJ4IZYJowVrqqq82s4Ek/4m3EGyoGBxFHIGfN1NFDI//vDftOgcvqTqLlx0bVkgwYOW4WszT1x3GCqQUOBo5M9qWZGHrmon96etq9yIIZEku6pZraedFL3Rg==" Feb 18 07:59:46 volumio volumio[11340]: info: Initializing connection to go-librespot Websocket Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="new websocket client" Feb 18 07:59:46 volumio volumio[11340]: info: Connection to go-librespot Websocket established Feb 18 07:59:46 volumio go-librespot[11679]: time="2026-02-18T07:59:46+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:47 volumio go-librespot[11679]: time="2026-02-18T07:59:47+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:47 volumio go-librespot[11679]: time="2026-02-18T07:59:47+07:00" level=debug msg="completed challenge" Feb 18 07:59:47 volumio go-librespot[11679]: time="2026-02-18T07:59:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:47 volumio volumio[11340]: info: Connection to go-librespot Websocket closed Feb 18 07:59:49 volumio volumio[11340]: info: Getting Spotify volume Feb 18 07:59:50 volumio volumio[11340]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 07:59:50 volumio volumio[11340]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 07:59:50 volumio volumio[11340]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 07:59:50 volumio volumio[11340]: errno: -111, Feb 18 07:59:50 volumio volumio[11340]: code: 'ECONNREFUSED', Feb 18 07:59:50 volumio volumio[11340]: syscall: 'connect', Feb 18 07:59:50 volumio volumio[11340]: address: '127.0.0.1', Feb 18 07:59:50 volumio volumio[11340]: port: 9879, Feb 18 07:59:50 volumio volumio[11340]: response: undefined Feb 18 07:59:50 volumio volumio[11340]: } Feb 18 07:59:50 volumio volumio[11340]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 07:59:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 18 07:59:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:50 volumio go-librespot[11698]: go-librespot daemon starting... Feb 18 07:59:50 volumio go-librespot[11699]: time="2026-02-18T07:59:50+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:50 volumio go-librespot[11699]: time="2026-02-18T07:59:50+07:00" level=debug msg="app state loaded" Feb 18 07:59:50 volumio go-librespot[11699]: time="2026-02-18T07:59:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:50 volumio go-librespot[11699]: time="2026-02-18T07:59:50+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:50 volumio go-librespot[11699]: time="2026-02-18T07:59:50+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:50 volumio go-librespot[11699]: time="2026-02-18T07:59:50+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=info msg="zeroconf server listening on port 41949" Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=debug msg="obtained new client token: AACwIhT2JcQbCbfanHmfKQUc0lH/PTPjmDrPC+zygJXKZ00nrAIY7bCtFfj7VFYKP7CGH5BWJIuny/uk+2h76gayLa2fPYDPr15ZoVLdmVYLfDL6UyLDpF936rT2OUm4uxOIfmyaCgvBFBvH7y6PbxC4d2qI45vEyDEQaubtL7DEFvpP5jE/NBsmSfQqrCLWcz/oDa3QGQ3WLagAqEsjCfYSadcHPXsTjcnWSVUgcsLOaoGkC+vTRGw=" Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=debug msg="completed challenge" Feb 18 07:59:51 volumio sudo[11723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 07:58' Feb 18 07:59:51 volumio sudo[11723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:51 volumio go-librespot[11699]: time="2026-02-18T07:59:51+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:51 volumio sudo[11723]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:52 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 07:59:52 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 07:59:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 07:59:52 volumio systemd[1]: volumio.service: Consumed 49.476s CPU time. Feb 18 07:59:52 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 07:59:52 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 07:59:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21609. Feb 18 07:59:52 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 07:59:52 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 07:59:52 volumio systemd[1]: volumio.service: Consumed 49.476s CPU time. Feb 18 07:59:52 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 07:59:52 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 07:59:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 18 07:59:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:54 volumio go-librespot[11752]: go-librespot daemon starting... Feb 18 07:59:54 volumio go-librespot[11753]: time="2026-02-18T07:59:54+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:54 volumio go-librespot[11753]: time="2026-02-18T07:59:54+07:00" level=debug msg="app state loaded" Feb 18 07:59:54 volumio go-librespot[11753]: time="2026-02-18T07:59:54+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=info msg="zeroconf server listening on port 36085" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="obtained new client token: AAAuRMX6OtqAUphc1+h0zZneJtbEi9lyChmSuab9ex7Y1sIwGUmAZq71mvEtmBOVDdvyUGxYzyXCpQnh/Pm91a6fXMRPnCyZOWBrk8BJB2IpY8qP2eiENURXoF7WwKe+KwsgXdHC6M3+QCC+wZeqpj8uOjuuVMdvmYcm3ntED1/nrk2Lv9XhROw58MIi/9J7aNlf5HuanSXqK1nPyzGvRzGbXqxjzYyIuZBSbY9z14z18iJUSqpHI3TnhA==" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 07:59:55 volumio volumio[11737]: info: ------------------------------------------- Feb 18 07:59:55 volumio volumio[11737]: info: ----- Volumio3 ---- Feb 18 07:59:55 volumio volumio[11737]: info: ------------------------------------------- Feb 18 07:59:55 volumio volumio[11737]: info: ----- System startup ---- Feb 18 07:59:55 volumio volumio[11737]: info: ------------------------------------------- Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="completed keyexchange" Feb 18 07:59:55 volumio go-librespot[11753]: time="2026-02-18T07:59:55+07:00" level=debug msg="completed challenge" Feb 18 07:59:56 volumio go-librespot[11753]: time="2026-02-18T07:59:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 07:59:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 07:59:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 07:59:57 volumio volumio-remote-updater[25144]: [2026-02-18 07:59:57] [connect] Successful connection Feb 18 07:59:57 volumio volumio[11737]: info: MYVOLUMIO Environment detected Feb 18 07:59:57 volumio volumio[11737]: info: Plugin folders cleanup Feb 18 07:59:57 volumio volumio[11737]: info: Scanning into folder /volumio/app/plugins/ Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category audio_interface Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category miscellanea Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category music_service Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category plugins.json Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category system_controller Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category user_interface Feb 18 07:59:57 volumio volumio[11737]: info: Scanning into folder /data/plugins/ Feb 18 07:59:57 volumio volumio[11737]: info: Scanning category music_service Feb 18 07:59:57 volumio volumio[11737]: info: Plugin folders cleanup completed Feb 18 07:59:57 volumio volumio[11737]: info: ------------------------------------------- Feb 18 07:59:57 volumio volumio[11737]: info: ----- Core plugins startup ---- Feb 18 07:59:57 volumio volumio[11737]: info: ------------------------------------------- Feb 18 07:59:57 volumio volumio[11737]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 07:59:57 volumio volumio[11737]: info: Adding plugin upnp to MyMusic Plugins Feb 18 07:59:57 volumio volumio[11737]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 07:59:57 volumio volumio[11737]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 07:59:57 volumio volumio[11737]: info: Loading plugins from folder /data/plugins/ Feb 18 07:59:57 volumio volumio[11737]: info: Loading plugin "system"... Feb 18 07:59:57 volumio volumio[11737]: info: Loading plugin "appearance"... Feb 18 07:59:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 18 07:59:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 07:59:59 volumio go-librespot[11774]: go-librespot daemon starting... Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=debug msg="app state loaded" Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "network"... Feb 18 07:59:59 volumio volumio[11737]: info: Refreshing Cached IP Addresses Feb 18 07:59:59 volumio sudo[11782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 07:59:59 volumio sudo[11782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:59 volumio sudo[11782]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:59 volumio sudo[11784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 07:59:59 volumio sudo[11784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:59 volumio sudo[11784]: pam_unix(sudo:session): session closed for user root Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "services"... Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "alsa_controller"... Feb 18 07:59:59 volumio sudo[11792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 07:59:59 volumio sudo[11792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 07:59:59 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "wizard"... Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "networkfs"... Feb 18 07:59:59 volumio volumio[11737]: info: Starting Udev Watcher for removable devices Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 07:59:59 volumio volumio[11737]: info: Ignoring mount for partition: boot Feb 18 07:59:59 volumio volumio[11737]: info: Ignoring mount for partition: volumio Feb 18 07:59:59 volumio volumio[11737]: info: Ignoring mount for partition: volumio_data Feb 18 07:59:59 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "volumio_command_line_client"... Feb 18 07:59:59 volumio go-librespot[11775]: time="2026-02-18T07:59:59+07:00" level=info msg="zeroconf server listening on port 36785" Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "upnp"... Feb 18 07:59:59 volumio volumio[11737]: info: [1771376399843] Starting Upmpd Daemon Feb 18 07:59:59 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "my_music"... Feb 18 07:59:59 volumio volumio[11737]: info: Loading plugin "mpd"... Feb 18 08:00:00 volumio go-librespot[11775]: time="2026-02-18T08:00:00+07:00" level=debug msg="obtained new client token: AAACHoC9CygkwK04x0NaImWhF3fuzkeOFWBsUotozoR3cFcC4aFnDrFTGusvCknFGMcRtsdLU5GgcXv3JDWL+PfNDjuhkCTHq507Zxusx7YJtKRfGP1KrUhimZzmlqjBPQgWNUPt/mgIwacMcUpplFiF8pKNAwN1mO1rItqdwTGc+60nem31HFs8aSg3UY47+/OKze/CFjzjMxRqCyXM3MycZyPCcqDL3A1BZobOX2KCLwfPWwo4yko=" Feb 18 08:00:00 volumio go-librespot[11775]: time="2026-02-18T08:00:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:00 volumio go-librespot[11775]: time="2026-02-18T08:00:00+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:00 volumio go-librespot[11775]: time="2026-02-18T08:00:00+07:00" level=debug msg="completed challenge" Feb 18 08:00:00 volumio go-librespot[11775]: time="2026-02-18T08:00:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:00 volumio volumio[11737]: info: Loading plugin "upnp_browser"... Feb 18 08:00:02 volumio sudo[11792]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:03 volumio volumio[11737]: info: Starting UPNP Browser Feb 18 08:00:03 volumio volumio[11737]: info: Loading plugin "alarm-clock"... Feb 18 08:00:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 18 08:00:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:03 volumio go-librespot[11819]: go-librespot daemon starting... Feb 18 08:00:03 volumio go-librespot[11820]: time="2026-02-18T08:00:03+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:03 volumio go-librespot[11820]: time="2026-02-18T08:00:03+07:00" level=debug msg="app state loaded" Feb 18 08:00:03 volumio go-librespot[11820]: time="2026-02-18T08:00:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:03 volumio volumio[11737]: info: Loading plugin "airplay_emulation"... Feb 18 08:00:03 volumio volumio[11737]: info: Starting Shairport Sync Feb 18 08:00:03 volumio volumio[11737]: info: Loading plugin "last_100"... Feb 18 08:00:03 volumio volumio[11737]: info: Loading plugin "webradio"... Feb 18 08:00:03 volumio volumio[11737]: info: Loading plugin "i2s_dacs"... Feb 18 08:00:04 volumio volumio[11737]: info: Loading plugin "volumiodiscovery"... Feb 18 08:00:04 volumio volumio[11737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 08:00:04 volumio volumio[11737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 08:00:04 volumio volumio[11737]: *** WARNING *** For more information see Feb 18 08:00:04 volumio volumio[11737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 08:00:04 volumio volumio[11737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 08:00:04 volumio volumio[11737]: *** WARNING *** For more information see Feb 18 08:00:04 volumio node[11737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 08:00:04 volumio node[11737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 08:00:04 volumio node[11737]: *** WARNING *** For more information see Feb 18 08:00:04 volumio node[11737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 08:00:04 volumio node[11737]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 08:00:04 volumio node[11737]: *** WARNING *** For more information see Feb 18 08:00:04 volumio volumio[11737]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 08:00:04 volumio volumio[11737]: info: Discovery: Started advertising with name: Volumio Feb 18 08:00:04 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 08:00:04 volumio volumio[11737]: info: Loading plugin "spop"... Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=info msg="zeroconf server listening on port 45721" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="obtained new client token: AAB2r/mHYd8IZ8exhR8aKiMFxJZbeBiSQXriIlPisx52ku1EMxC1mqQ1q5CKs703H2IV7jWmYTsoL3f4SUpKX4LVcNR1JRUGiPiOQxk6unv5QUphb9EEnuM/WCDHuC+04uOn2jYFZ0wZ5Aus0xRTLoHhaF1tIWX5dzGzIsLDQAM8ZpIBtSoGz2L966B/WEMQ6TEu90bzasPMgz9lgIQSw6317niY7wXCi6e9KxdSF+EmRG0RKfe2d4e0TQ==" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=debug msg="completed challenge" Feb 18 08:00:04 volumio go-librespot[11820]: time="2026-02-18T08:00:04+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:05 volumio volumio[11737]: info: Loading plugin "ytcr"... Feb 18 08:00:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 18 08:00:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:08 volumio go-librespot[11829]: go-librespot daemon starting... Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=debug msg="app state loaded" Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:08 volumio volumio[11737]: info: Loading plugin "ytmusic"... Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 08:00:08 volumio go-librespot[11830]: time="2026-02-18T08:00:08+07:00" level=info msg="zeroconf server listening on port 44585" Feb 18 08:00:09 volumio go-librespot[11830]: time="2026-02-18T08:00:09+07:00" level=debug msg="obtained new client token: AADvKh2xd8UEt+liUpTiVDQs2Kc4ANPwsfHDQLqj+a/Yfxb1gFFW7WXIS0zUEljX0q106VkkAhMCjy56g4go7btlLlTXtSdZAuZ2ZbjpVQN1GI7J9ITZ2q1nc4OhWagkGxQJZwv95z/V0sV8rXESFJfUKxWdE2pKd+ZhklbEp/7OB3oBviwDo2ZipDwjyMhanRTqoUnFurjVGcbGkCsJBMI8GvLNqQglQZ597xeURBah7Lm4pO/XzCjq3Q==" Feb 18 08:00:09 volumio go-librespot[11830]: time="2026-02-18T08:00:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:09 volumio go-librespot[11830]: time="2026-02-18T08:00:09+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:09 volumio go-librespot[11830]: time="2026-02-18T08:00:09+07:00" level=debug msg="completed challenge" Feb 18 08:00:09 volumio go-librespot[11830]: time="2026-02-18T08:00:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:09 volumio volumio-remote-updater[25144]: [2026-02-18 08:00:09] [connect] Successful connection Feb 18 08:00:09 volumio volumio[11737]: info: Loading plugin "outputs"... Feb 18 08:00:09 volumio volumio[11737]: info: Loading plugin "albumart"... Feb 18 08:00:09 volumio volumio[11737]: info: Plugin example_plugin is not enabled Feb 18 08:00:09 volumio volumio[11737]: info: Loading plugin "inputs"... Feb 18 08:00:09 volumio volumio[11737]: info: Loading plugin "updater_comm"... Feb 18 08:00:09 volumio volumio[11737]: info: Plugin mpdemulation is not enabled Feb 18 08:00:09 volumio volumio[11737]: info: Loading plugin "rest_api"... Feb 18 08:00:10 volumio volumio[11737]: info: Loading plugin "websocket"... Feb 18 08:00:10 volumio volumio[11737]: info: Starting Socket.io Server version 1.7.4 Feb 18 08:00:10 volumio volumio[11737]: info: Loading plugin "RoonBridge"... Feb 18 08:00:10 volumio volumio[11737]: info: Applying required configuration parameters for plugin RoonBridge Feb 18 08:00:10 volumio volumio[11737]: info: Loading i18n strings for locale en Feb 18 08:00:10 volumio volumio[11737]: Updating browse sources language Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::initPlayerControls Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 08:00:10 volumio volumio[11737]: Express server listening on port 3000 Feb 18 08:00:10 volumio volumio[11737]: [Metrics] WebUI: 16s 39.98ms Feb 18 08:00:10 volumio volumio[11852]: Forking 3 albumart workers Feb 18 08:00:10 volumio volumio[11737]: info: CoreStateMachine::resetVolumioState Feb 18 08:00:10 volumio volumio[11737]: info: CoreStateMachine::getcurrentVolume Feb 18 08:00:10 volumio volumio[11737]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 08:00:11 volumio sudo[11870]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 08:00:11 volumio sudo[11870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:11 volumio sudo[11870]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:11 volumio sudo[11872]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 08:00:11 volumio sudo[11872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:11 volumio sudo[11872]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:11 volumio volumio[11737]: info: Volumio Network Manager: Network status updated: 1 Feb 18 08:00:11 volumio volumio[11737]: info: VolumeController:: Volume=100 Mute =false Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::pushState Feb 18 08:00:11 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::volumioPushState Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::updateTrackBlock Feb 18 08:00:11 volumio volumio[11737]: info: CorePlayQueue::getTrackBlock Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 08:00:11 volumio volumio-remote-updater[25144]: [2026-02-18 08:00:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771376409 101 Feb 18 08:00:11 volumio volumio[11737]: 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: 1 Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:11 volumio volumio[11737]: info: Reloading queue from file Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::setRepeat null single undefined Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::pushState Feb 18 08:00:11 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::volumioPushState Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::setRandom null Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::pushState Feb 18 08:00:11 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::volumioPushState Feb 18 08:00:11 volumio volumio[11737]: info: Setting Device type: Raspberry PI Feb 18 08:00:11 volumio volumio[11737]: info: Completed loading Core Plugins Feb 18 08:00:11 volumio volumio[11737]: info: Preparing to generate the ALSA configuration file Feb 18 08:00:11 volumio volumio[11737]: info: VolumeController:: Volume=100 Mute =false Feb 18 08:00:11 volumio volumio[11737]: info: CoreStateMachine::pushState Feb 18 08:00:11 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:11 volumio volumio[11737]: info: CoreCommandRouter::volumioPushState Feb 18 08:00:11 volumio volumio[11737]: info: Asound.conf file unchanged, so no further update is needed Feb 18 08:00:11 volumio volumio[11737]: info: Output device has changed, restarting MPD Feb 18 08:00:12 volumio volumio[11737]: info: Output device has changed, restarting Shairport Sync Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:12 volumio sudo[11912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 08:00:12 volumio sudo[11912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:12 volumio sudo[11914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 08:00:12 volumio sudo[11914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:12 volumio volumio[11737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 08:00:12 volumio sudo[11914]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:12 volumio volumio[11737]: info: ___________ START PLUGINS ___________ Feb 18 08:00:12 volumio sudo[11916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 08:00:12 volumio sudo[11916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:12 volumio volumio[11737]: info: ControllerMpd::onStart: Initializing MPD Feb 18 08:00:12 volumio volumio[11737]: info: Creating MPD Configuration file Feb 18 08:00:12 volumio sudo[11912]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 08:00:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 08:00:12 volumio volumio[11737]: info: [1771376412267] CoreMusicLibrary::Adding element Media Servers Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 08:00:12 volumio sudo[11927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 08:00:12 volumio sudo[11927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:12 volumio sudo[11925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 08:00:12 volumio sudo[11925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:12 volumio volumio[11737]: info: UPNP Browser: Client initialized successfully Feb 18 08:00:12 volumio sudo[11925]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 08:00:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 08:00:12 volumio systemd[1]: mpd.service: Consumed 7.159s CPU time. Feb 18 08:00:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 18 08:00:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 08:00:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 08:00:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:12 volumio volumio[11737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 08:00:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:12 volumio go-librespot[11939]: go-librespot daemon starting... Feb 18 08:00:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 08:00:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 08:00:12 volumio volumio[11737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 08:00:12 volumio volumio[11737]: info: [1771376412666] CoreMusicLibrary::Adding element Last_100 Feb 18 08:00:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 08:00:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 08:00:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 08:00:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 08:00:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 08:00:12 volumio volumio[11737]: info: [1771376412711] CoreMusicLibrary::Adding element Webradio Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 08:00:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 08:00:12 volumio go-librespot[11940]: time="2026-02-18T08:00:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:12 volumio go-librespot[11940]: time="2026-02-18T08:00:12+07:00" level=debug msg="app state loaded" Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 08:00:12 volumio go-librespot[11940]: time="2026-02-18T08:00:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:12 volumio volumio[11737]: info: Initializing BBC Radios Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 08:00:12 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:13 volumio volumio[11737]: info: Creating Spotify config file Feb 18 08:00:13 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:13 volumio sudo[11949]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 08:00:13 volumio sudo[11949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 08:00:13 volumio sudo[11949]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=info msg="zeroconf server listening on port 42291" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="obtained new client token: AACdK8xKIdzlG3uYQ29P/d9QN/m7T3Z62w/Kbfv0JidCWHIS+taYhzc/l0z01f8PByjYfUgy1zsr0Jzwroq50BRQBb1eyLolnuP2udg3gCwA32rDyMc0QOHyn6357e9Ojy32kXKbSwT/AlQeEIXkdrMklZFftF06UzY2qqM2SIeNWvm3YjEDHltyMGpQpU0Zg6Z6XutQYUy6VW47n40cqJAR9XcJaGS5CC/Jq/zsFL8o+73ZPetp5Gnsxw==" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:13 volumio go-librespot[11940]: time="2026-02-18T08:00:13+07:00" level=debug msg="completed challenge" Feb 18 08:00:14 volumio go-librespot[11940]: time="2026-02-18T08:00:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:14 volumio volumio[11737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 08:00:14 volumio volumio[11737]: info: [1771376414349] CoreMusicLibrary::Adding element YouTube Music Feb 18 08:00:14 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 08:00:14 volumio volumio[11737]: Cannot find translation for source YouTube Music Feb 18 08:00:14 volumio volumio[11737]: info: Volumio Calling Home Feb 18 08:00:14 volumio sudo[11968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 08:00:14 volumio sudo[11968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:14 volumio sudo[11968]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:14 volumio volumio[11868]: Starting albumart workers Feb 18 08:00:14 volumio volumio[11865]: Starting albumart workers Feb 18 08:00:14 volumio volumio[11867]: Starting albumart workers Feb 18 08:00:15 volumio volumio[11737]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 08:00:15 volumio volumio[11737]: info: Discovery: Found device Volumio Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::volumioGetState Feb 18 08:00:15 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:15 volumio volumio[11737]: info: MPD Permissions set Feb 18 08:00:15 volumio volumio[11737]: info: MPD Permissions set Feb 18 08:00:15 volumio volumio[11737]: info: Upmpdcli Daemon Started Feb 18 08:00:15 volumio volumio[11737]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 08:00:15 volumio volumio[11737]: info: Discovery: Found device Volumio Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::volumioGetState Feb 18 08:00:15 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:15 volumio volumio[11737]: info: Spotify config file written Feb 18 08:00:15 volumio sudo[11974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 08:00:15 volumio sudo[11974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:15 volumio volumio[11737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio go-librespot[11980]: go-librespot daemon starting... Feb 18 08:00:15 volumio sudo[11974]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:15 volumio go-librespot[11982]: time="2026-02-18T08:00:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:15 volumio go-librespot[11982]: time="2026-02-18T08:00:15+07:00" level=debug msg="app state loaded" Feb 18 08:00:15 volumio go-librespot[11982]: time="2026-02-18T08:00:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 08:00:15 volumio volumio[11737]: info: No need to fix Spotify hosts Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00: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-gew1.spotify.com:80]" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=info msg="zeroconf server listening on port 37499" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=debug msg="obtained new client token: AAANaa2+uH5mL0wihA9cxRbEmRdBlpyneZ0D4zbSKXJC4GfLIBAPOk/6Xmsd6t459Y/spk22tMrjp99NqH/7EuTnWXKpBaTq/XxCQe5cgJUkbXGQth5JNtyBsFJuoj6tW7OOZdH6Xp48lTvUaAc67UiFYwxR+Lq3f7HueLzDYchbmuTk2uIQZf7wDCnN9Si50Sq2KSiLk04Hhf3j7YuNOmA6HxIC2OAkNBeiQ1lpYxDq+xQSWDbj6SDEOg==" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:16 volumio go-librespot[11982]: time="2026-02-18T08:00:16+07:00" level=debug msg="completed challenge" Feb 18 08:00:17 volumio go-librespot[11982]: time="2026-02-18T08:00: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 18 08:00:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:17 volumio volumio[11737]: info: Volumio called home Feb 18 08:00:17 volumio volumio[11737]: info: Starting Shairport Sync Feb 18 08:00:17 volumio volumio[11737]: info: Starting Shairport Sync Feb 18 08:00:17 volumio volumio[11737]: info: Starting Shairport Sync Feb 18 08:00:17 volumio sudo[12013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 08:00:17 volumio sudo[12013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:17 volumio sudo[12015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 08:00:17 volumio sudo[12015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:17 volumio sudo[12017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 08:00:17 volumio sudo[12017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:17 volumio volumio[11737]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 08:00:17 volumio volumio[11737]: SPOTIFY: BQCUT3yMGGU7PdsejTWi9unI_Pccb03E1k1hmOozkSkwmLkLYQxBlfiuVCOD11ZjCyXJ2ZeMPtBNe8m0eb5jBCUfgS_7NHbngGmCKaPl88F_9UVh0GI6oopWLQ8SaS__qzTHB13MNaWtqmvnLqIS9N9kHspgBiUhGC0wPnXLuuFbGm1vTQtWhfJ93RUryP2soX74lZGpPJM_7XhnC3pGQ2LjTPOl3rUetmzIUCrsnnG9bzCmcnJShRnkd7llVyD3fs7fqD-UmNPI6iadNY-_jPN-FNIQdRb0pcqbqUgkfgE3NRrKUq6o0-yl Feb 18 08:00:17 volumio volumio[11737]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 08:00:17 volumio volumio[11737]: info: New Spotify access token = BQCUT3yMGGU7PdsejTWi9unI_Pccb03E1k1hmOozkSkwmLkLYQxBlfiuVCOD11ZjCyXJ2ZeMPtBNe8m0eb5jBCUfgS_7NHbngGmCKaPl88F_9UVh0GI6oopWLQ8SaS__qzTHB13MNaWtqmvnLqIS9N9kHspgBiUhGC0wPnXLuuFbGm1vTQtWhfJ93RUryP2soX74lZGpPJM_7XhnC3pGQ2LjTPOl3rUetmzIUCrsnnG9bzCmcnJShRnkd7llVyD3fs7fqD-UmNPI6iadNY-_jPN-FNIQdRb0pcqbqUgkfgE3NRrKUq6o0-yl Feb 18 08:00:17 volumio volumio[11737]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 08:00:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 08:00:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 08:00:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 08:00:17 volumio systemd[1]: shairport-sync.service: Consumed 2.174s CPU time. Feb 18 08:00:17 volumio volumio[11737]: info: CoreCommandRouter::volumioGetState Feb 18 08:00:17 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 08:00:17 volumio sudo[12015]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:17 volumio sudo[12017]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:17 volumio sudo[12013]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:17 volumio volumio[11737]: info: Shairport-Sync Started Feb 18 08:00:17 volumio volumio[11737]: Error adding Membership: Error: addMembership EINVAL Feb 18 08:00:18 volumio volumio[11737]: info: Shairport-Sync Started Feb 18 08:00:18 volumio volumio[11737]: info: Shairport-Sync Started Feb 18 08:00:18 volumio volumio[11737]: 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 18 08:00:18 volumio volumio[11737]: info: Spotify Successfully logged in Feb 18 08:00:18 volumio volumio[11737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 08:00:18 volumio volumio[11737]: info: [1771376418207] CoreMusicLibrary::Adding element Spotify Feb 18 08:00:18 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 08:00:18 volumio volumio[11737]: Cannot find translation for source YouTube Music Feb 18 08:00:18 volumio volumio[11737]: Cannot find translation for source Spotify Feb 18 08:00:18 volumio volumio[11737]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 08:00:18 volumio volumio[11737]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 08:00:19 volumio volumio[11737]: info: VolumeController:: Volume=100 Mute =false Feb 18 08:00:19 volumio volumio[11737]: info: CoreCommandRouter::volumioGetState Feb 18 08:00:19 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:19 volumio volumio[11737]: info: CoreStateMachine::pushState Feb 18 08:00:19 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:19 volumio volumio[11737]: info: CoreCommandRouter::volumioPushState Feb 18 08:00:20 volumio volumio[11737]: info: go-librespot daemon successfully initialized Feb 18 08:00:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 18 08:00:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:20 volumio go-librespot[12053]: go-librespot daemon starting... Feb 18 08:00:20 volumio go-librespot[12054]: time="2026-02-18T08:00:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:20 volumio go-librespot[12054]: time="2026-02-18T08:00:20+07:00" level=debug msg="app state loaded" Feb 18 08:00:20 volumio go-librespot[12054]: time="2026-02-18T08:00:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:20 volumio mpd[11965]: 2026-02-18T08:00:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 08:00:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 08:00:20 volumio sudo[11927]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:20 volumio sudo[11916]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:20 volumio volumio[11737]: error: MPD error: The expression evaluated to a falsy value: Feb 18 08:00:20 volumio volumio[11737]: assert.ok(self.idling) Feb 18 08:00:20 volumio volumio[11737]: error: The expression evaluated to a falsy value: Feb 18 08:00:20 volumio volumio[11737]: assert.ok(self.idling) Feb 18 08:00:20 volumio volumio[11737]: error: updateQueue error: null Feb 18 08:00:20 volumio volumio[11737]: info: MPD running with PID11965 Feb 18 08:00:20 volumio volumio[11737]: ,establishing connection Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 08:00:21 volumio volumio[11737]: info: Completed starting Core Plugins Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=info msg="zeroconf server listening on port 40081" Feb 18 08:00:21 volumio volumio[11737]: info: ------------------------------------------- Feb 18 08:00:21 volumio volumio[11737]: info: ----- MyVolumio plugins startup ---- Feb 18 08:00:21 volumio volumio[11737]: info: ------------------------------------------- Feb 18 08:00:21 volumio volumio[11737]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 08:00:21 volumio volumio[11737]: error: updateQueue error: null Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="obtained new client token: AABmUbZJOda3gVPicItA/ap8W7m4DF/PI/f6EcqLORtTY7+PWw1QuG44MFDWwarYM2pvNt83oTugMEsjCSsmAAKgmQHcLh5PjXeD5oAbVRp7H4ySXhaGcO4BRnAYFdSMQzV3e/oOf22xAbjy0ITvJvMR8TiXfbSN8leTkV5LyUQyxz8v9/9JEnDbyDvZlWaz5uRnEodqDUn/BrHq9mwFmGnfE6lEbr7Pu1O/RhqMnwkhEyTVsDWAkIE=" Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=debug msg="completed challenge" Feb 18 08:00:21 volumio go-librespot[12054]: time="2026-02-18T08:00:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:23 volumio volumio[11737]: info: Initializing connection to go-librespot Websocket Feb 18 08:00:23 volumio volumio[11737]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 08:00:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 18 08:00:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:24 volumio go-librespot[12067]: go-librespot daemon starting... Feb 18 08:00:24 volumio go-librespot[12068]: time="2026-02-18T08:00:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:24 volumio go-librespot[12068]: time="2026-02-18T08:00:24+07:00" level=debug msg="app state loaded" Feb 18 08:00:24 volumio go-librespot[12068]: time="2026-02-18T08:00:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=info msg="zeroconf server listening on port 39045" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="obtained new client token: AAB/Su/tiIk+4PYlQu3qBt4CoybPOXgcVh4RBsZWs7azARmPZhZ1yMbtGvaetXmBKkBun11sMxUf17sqoatFxFmCKgk8GumGdu48I4JsA6TmoXEIC1DqgCeQpgHa6RLr6QSnqjmHg7U98go3d2jIeUpqWzveb/NHaBdgMs+CjaGBB/Su2Ga7Z2px5Ul8g3BKzho1NoVP5ErPiS/SQIgPO7XFSEszVP1xuUwrJLJslP7pNDgixpLhNzaa8g==" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:25 volumio volumio[11737]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=debug msg="completed challenge" Feb 18 08:00:25 volumio go-librespot[12068]: time="2026-02-18T08:00:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:26 volumio volumio[11737]: info: Initializing connection to go-librespot Websocket Feb 18 08:00:26 volumio volumio[11737]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 08:00:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 18 08:00:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:29 volumio go-librespot[12075]: go-librespot daemon starting... Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="app state loaded" Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:29 volumio volumio[11737]: info: Initializing connection to go-librespot Websocket Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="new websocket client" Feb 18 08:00:29 volumio volumio[11737]: info: Connection to go-librespot Websocket established Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=info msg="zeroconf server listening on port 33173" Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 08:00:29 volumio volumio[11737]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="obtained new client token: AAB8cJTFVxrbK1pjBrLVAYbUH8pPpZ/j/DaqlirNEFtN7VGBRWqCqpNqSHgNRWAF4l+rJqti6bwHCNN/WlGvi5cQyf3EpNinoZ+U/XPJTMao6B8pPlpKR/8aagLHw4QeDK4m7Dds57yh5IqWb1pDGvLsRuRq0LBYJvFT6xMZj6dNrf8GFMl8pwSUA/JvaNfBljTvbSW6sK3rZ1IC7XntDsV5Fw9yQmAppG7zuuYL52dIuIDZIop9sNeclA==" Feb 18 08:00:29 volumio go-librespot[12076]: time="2026-02-18T08:00:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:30 volumio go-librespot[12076]: time="2026-02-18T08:00:30+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:30 volumio go-librespot[12076]: time="2026-02-18T08:00:30+07:00" level=debug msg="completed challenge" Feb 18 08:00:30 volumio go-librespot[12076]: time="2026-02-18T08:00:30+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:31 volumio volumio[11737]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 08:00:31 volumio volumio[11737]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 08:00:31 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:31 volumio volumio[11737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 08:00:31 volumio volumio[11737]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 08:00:31 volumio volumio[11737]: info: MyVolumio login type: Token Feb 18 08:00:31 volumio volumio[11737]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 08:00:31 volumio volumio[11737]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 08:00:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 18 08:00:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 08:00:33 volumio go-librespot[12099]: go-librespot daemon starting... Feb 18 08:00:33 volumio go-librespot[12100]: time="2026-02-18T08:00:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 08:00:33 volumio go-librespot[12100]: time="2026-02-18T08:00:33+07:00" level=debug msg="app state loaded" Feb 18 08:00:33 volumio go-librespot[12100]: time="2026-02-18T08:00:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 08:00:33 volumio volumio[11737]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 08:00:33 volumio volumio[11737]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 08:00:33 volumio volumio[11737]: info: Streaming services startup Feb 18 08:00:33 volumio volumio[11737]: info: Starting Streaming Daemon Feb 18 08:00:33 volumio sudo[12108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 08:00:33 volumio sudo[12108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 08:00:33 volumio volumio[11737]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 08:00:33 volumio sudo[12108]: pam_unix(sudo:session): session closed for user root Feb 18 08:00:33 volumio volumio[11737]: info: Getting Spotify volume Feb 18 08:00:33 volumio volumio[11737]: info: Connection to go-librespot Websocket closed Feb 18 08:00:33 volumio volumio[11737]: error: Cannot start Volumio Streaming Daemon Feb 18 08:00:33 volumio volumio[11737]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 08:00:33 volumio volumio[11737]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 08:00:33 volumio volumio[11737]: 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 18 08:00:33 volumio go-librespot[12100]: time="2026-02-18T08:00:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 08:00:33 volumio go-librespot[12100]: time="2026-02-18T08:00:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 08:00:33 volumio go-librespot[12100]: time="2026-02-18T08:00:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 08:00:34 volumio volumio[11737]: info: CoreCommandRouter::volumioGetState Feb 18 08:00:34 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:34 volumio go-librespot[12100]: time="2026-02-18T08:00:34+07:00" level=info msg="zeroconf server listening on port 40977" Feb 18 08:00:34 volumio volumio[11737]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 08:00:34 volumio volumio[11737]: SPOTIFY: SPOTIFY VOLUME undefined Feb 18 08:00:34 volumio volumio[11737]: SPOTIFY: VOLUMIO VOLUME 100 Feb 18 08:00:34 volumio volumio[11737]: info: Aligning Spotify Volume to Volumio Volume Feb 18 08:00:34 volumio volumio[11737]: info: CoreCommandRouter::volumioGetState Feb 18 08:00:34 volumio volumio[11737]: info: CorePlayQueue::getTrack 0 Feb 18 08:00:34 volumio volumio[11737]: info: Setting Spotify Volume from Volumio: 100 Feb 18 08:00:34 volumio go-librespot[12100]: time="2026-02-18T08:00:34+07:00" level=debug msg="obtained new client token: AABT3gj3HgILasEEtxJ88v/hehCoqBcLjQMnE7qq3RPU6/p+Ea2+GDK64PsQYihovJrjYWeOMouKHk5U/fHgQICWuoUtIdafhsi7fFxiTVbREIKGBncLa5DW9nQijBifufG7dowukW4rfqTNAC+/ObIL0BUMGlM4/jx0g7EV5GNljlYbC3TjFBD1eWJSPovMg6Bl5Wmo8A0dtTvk2oJKKR4gcefdKYPzGMBXZb9VviPlrI5kxSnfGC0=" Feb 18 08:00:34 volumio go-librespot[12100]: time="2026-02-18T08:00:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 08:00:34 volumio go-librespot[12100]: time="2026-02-18T08:00:34+07:00" level=debug msg="completed keyexchange" Feb 18 08:00:34 volumio go-librespot[12100]: time="2026-02-18T08:00:34+07:00" level=debug msg="completed challenge" Feb 18 08:00:34 volumio go-librespot[12100]: time="2026-02-18T08:00:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 08:00:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 08:00:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 08:00:34 volumio volumio[11737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 08:00:34 volumio volumio[11737]: Error: socket hang up Feb 18 08:00:34 volumio volumio[11737]: at connResetException (node:internal/errors:720:14) Feb 18 08:00:34 volumio volumio[11737]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 18 08:00:34 volumio volumio[11737]: at Socket.emit (node:events:526:35) Feb 18 08:00:34 volumio volumio[11737]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 18 08:00:34 volumio volumio[11737]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 18 08:00:34 volumio volumio[11737]: code: 'ECONNRESET', Feb 18 08:00:34 volumio volumio[11737]: response: undefined Feb 18 08:00:34 volumio volumio[11737]: } Feb 18 08:00:34 volumio volumio[11737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 08:00:36 volumio sudo[12129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 07:59' Feb 18 08:00:36 volumio sudo[12129]: 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"