Feb 12 20:59:00 volumio volumio[7354]: info: go-librespot daemon successfully initialized Feb 12 20:59:00 volumio mpd[7583]: 2026-02-12T20:59:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 20:59:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 20:59:00 volumio sudo[7534]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:00 volumio sudo[7547]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:00 volumio volumio[7354]: error: MPD error: The expression evaluated to a falsy value: Feb 12 20:59:00 volumio volumio[7354]: assert.ok(self.idling) Feb 12 20:59:00 volumio volumio[7354]: error: The expression evaluated to a falsy value: Feb 12 20:59:00 volumio volumio[7354]: assert.ok(self.idling) Feb 12 20:59:00 volumio volumio[7354]: error: updateQueue error: null Feb 12 20:59:00 volumio volumio[7354]: info: MPD running with PID7583 Feb 12 20:59:00 volumio volumio[7354]: ,establishing connection Feb 12 20:59:01 volumio volumio[7354]: info: Completed starting Core Plugins Feb 12 20:59:01 volumio volumio[7354]: info: ------------------------------------------- Feb 12 20:59:01 volumio volumio[7354]: info: ----- MyVolumio plugins startup ---- Feb 12 20:59:01 volumio volumio[7354]: info: ------------------------------------------- Feb 12 20:59:01 volumio volumio[7354]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 20:59:01 volumio volumio[7354]: error: updateQueue error: null Feb 12 20:59:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 12 20:59:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:01 volumio go-librespot[7678]: go-librespot daemon starting... Feb 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+07:00" level=debug msg="app state loaded" Feb 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+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 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+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 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+07:00" level=info msg="zeroconf server listening on port 45803" Feb 12 20:59:01 volumio go-librespot[7679]: time="2026-02-12T20:59:01+07:00" level=debug msg="obtained new client token: AAAhjYyy6khxgaF0FDhoTacZCzu6LsDWnGpbc5DBVg64DyjvzhNnt/vpLWbXJzyyPMF1stFndmAwZwcXeIye80S0khkaoeOovS9z8dtJQI1fhZrQem8RDxyOCsrN5qU8nOevHeb7VlSJGzzTlPPWd/aSGXJJoupAM4BXJC//MgJCqqQL9bIph0pFqqrxjW8RaEOOgOfAoYImU4H2nGz9msDI/YFcCt0Eh1FbD8r3jiCVuWk9XduPSTD26w==" Feb 12 20:59:02 volumio go-librespot[7679]: time="2026-02-12T20:59:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:02 volumio go-librespot[7679]: time="2026-02-12T20:59:02+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:02 volumio go-librespot[7679]: time="2026-02-12T20:59:02+07:00" level=debug msg="completed challenge" Feb 12 20:59:02 volumio go-librespot[7679]: time="2026-02-12T20:59:02+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 12 20:59:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:03 volumio volumio[7354]: info: Initializing connection to go-librespot Websocket Feb 12 20:59:03 volumio volumio[7354]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:59:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 12 20:59:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:05 volumio go-librespot[7686]: go-librespot daemon starting... Feb 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20:59:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20:59:05+07:00" level=debug msg="app state loaded" Feb 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20:59:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:05 volumio volumio[7354]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20:59:05+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 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20:59:05+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 12 20:59:05 volumio go-librespot[7687]: time="2026-02-12T20:59:05+07:00" level=info msg="zeroconf server listening on port 38423" Feb 12 20:59:06 volumio go-librespot[7687]: time="2026-02-12T20:59:06+07:00" level=debug msg="obtained new client token: AABOrfBXcMVCRyq3H2CNu1i8UH8af0ldnyk2TnNOqmsnVZFfT6S8uBHakf/GQsIgCEjf5ZvOnSql4iK/1Rhc4A012mpgjq4x1erNAdrEAEm8Me6/8aF1oH6bPC/Gp8etGUhAc7HRjRTPAu0fMkiUrZs1X4tevsDb9PvTY0czHs3kOFCuhKRaRi+JKwpT9+6NfdLAJqCTsBrR7cP4uE0HQuHWNX+hazjm5GSXAu4xko8YZS8UXNuBGO8=" Feb 12 20:59:06 volumio go-librespot[7687]: time="2026-02-12T20:59:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:06 volumio volumio[7354]: info: Initializing connection to go-librespot Websocket Feb 12 20:59:06 volumio go-librespot[7687]: time="2026-02-12T20:59:06+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:06 volumio go-librespot[7687]: time="2026-02-12T20:59:06+07:00" level=debug msg="completed challenge" Feb 12 20:59:06 volumio go-librespot[7687]: time="2026-02-12T20:59:06+07:00" level=debug msg="new websocket client" Feb 12 20:59:06 volumio volumio[7354]: info: Connection to go-librespot Websocket established Feb 12 20:59:06 volumio go-librespot[7687]: time="2026-02-12T20: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 12 20:59:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:06 volumio volumio[7354]: info: Connection to go-librespot Websocket closed Feb 12 20:59:09 volumio volumio[7354]: info: Getting Spotify volume Feb 12 20:59:09 volumio volumio[7354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:59:09 volumio volumio[7354]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:59:09 volumio volumio[7354]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 20:59:09 volumio volumio[7354]: errno: -111, Feb 12 20:59:09 volumio volumio[7354]: code: 'ECONNREFUSED', Feb 12 20:59:09 volumio volumio[7354]: syscall: 'connect', Feb 12 20:59:09 volumio volumio[7354]: address: '127.0.0.1', Feb 12 20:59:09 volumio volumio[7354]: port: 9879, Feb 12 20:59:09 volumio volumio[7354]: response: undefined Feb 12 20:59:09 volumio volumio[7354]: } Feb 12 20:59:09 volumio volumio[7354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:59:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 12 20:59:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:09 volumio go-librespot[7706]: go-librespot daemon starting... Feb 12 20:59:09 volumio go-librespot[7707]: time="2026-02-12T20:59:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:09 volumio go-librespot[7707]: time="2026-02-12T20:59:09+07:00" level=debug msg="app state loaded" Feb 12 20:59:09 volumio go-librespot[7707]: time="2026-02-12T20:59:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+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 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+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 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+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 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+07:00" level=info msg="zeroconf server listening on port 45395" Feb 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+07:00" level=debug msg="obtained new client token: AAARK1ThKBFPFHvsVPWOupHi7fjcc4vGgVYhR5AYgZ4KzJxlUN03NRXzAwrIA76wGAkILkoDxL4HtFFh0hmrTzeFJ/Teqel+TlUWEPzl+4/3c23AJP+THVoklW/cJ6jPcuWfzeNNxDPgwE70rYp1BnFku+el+AVh2X9TIv8OzmaTleVI1bO1fq52WcyvK1vkShuV8+TiZCzhfcDuVaE7DpXVQyoCsZNKTJgZ8D2KzIgNeJ7Kqeht54rqXQ==" Feb 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20:59:10+07:00" level=debug msg="completed challenge" Feb 12 20:59:10 volumio go-librespot[7707]: time="2026-02-12T20: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 12 20:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:10 volumio sudo[7732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 20:58' Feb 12 20:59:10 volumio sudo[7732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:11 volumio sudo[7732]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:11 volumio volumio-remote-updater[643]: [2026-02-12 20:59:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 12 20:59:11 volumio volumio-remote-updater[643]: [2026-02-12 20:59:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 12 20:59:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 20:59:11 volumio systemd[1]: volumio.service: Consumed 49.144s CPU time. Feb 12 20:59:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 20:59:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 20:59:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13747. Feb 12 20:59:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 20:59:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 20:59:11 volumio systemd[1]: volumio.service: Consumed 49.144s CPU time. Feb 12 20:59:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 20:59:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 20:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Feb 12 20:59:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:13 volumio go-librespot[7761]: go-librespot daemon starting... Feb 12 20:59:13 volumio go-librespot[7762]: time="2026-02-12T20:59:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:13 volumio go-librespot[7762]: time="2026-02-12T20:59:13+07:00" level=debug msg="app state loaded" Feb 12 20:59:13 volumio go-librespot[7762]: time="2026-02-12T20:59:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20: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 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20: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 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20: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 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20:59:14+07:00" level=info msg="zeroconf server listening on port 41067" Feb 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20:59:14+07:00" level=debug msg="obtained new client token: AABWCIZ7lt0/fru51BGuT7HVUXDFIPryTR2uuOJ+cyJzXL1yNkf4eDfoxg4jNCR3co2heVoWhw1ANLfHa1axxXe8rs59CJ2ed9nOgLcQyzqLPEsBFHFOKx3e20C3rqC+OedNheuBKXhoePB2nbrV7qf7mqheqHaf2efCFsGd8VI78FCkFmhBqOfpN96MIFa0hxG2avlhEc8DkNTIMRX7IBJw7N6kLM8TVsFPG2DDY99axUmjdWbQYhQdFg==" Feb 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20:59:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20:59:14+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20:59:14+07:00" level=debug msg="completed challenge" Feb 12 20:59:14 volumio go-librespot[7762]: time="2026-02-12T20: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 12 20:59:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:15 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:15 volumio volumio[7745]: info: ----- Volumio3 ---- Feb 12 20:59:15 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:15 volumio volumio[7745]: info: ----- System startup ---- Feb 12 20:59:15 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:16 volumio volumio-remote-updater[643]: [2026-02-12 20:59:16] [connect] Successful connection Feb 12 20:59:16 volumio volumio[7745]: info: MYVOLUMIO Environment detected Feb 12 20:59:16 volumio volumio[7745]: info: Plugin folders cleanup Feb 12 20:59:16 volumio volumio[7745]: info: Scanning into folder /volumio/app/plugins/ Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category audio_interface Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category miscellanea Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category music_service Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category plugins.json Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category system_controller Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category user_interface Feb 12 20:59:16 volumio volumio[7745]: info: Scanning into folder /data/plugins/ Feb 12 20:59:16 volumio volumio[7745]: info: Scanning category music_service Feb 12 20:59:16 volumio volumio[7745]: info: Plugin folders cleanup completed Feb 12 20:59:16 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:16 volumio volumio[7745]: info: ----- Core plugins startup ---- Feb 12 20:59:16 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:16 volumio volumio[7745]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 20:59:16 volumio volumio[7745]: info: Adding plugin upnp to MyMusic Plugins Feb 12 20:59:16 volumio volumio[7745]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 20:59:16 volumio volumio[7745]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 20:59:16 volumio volumio[7745]: info: Loading plugins from folder /data/plugins/ Feb 12 20:59:16 volumio volumio[7745]: info: Loading plugin "system"... Feb 12 20:59:16 volumio volumio[7745]: info: Loading plugin "appearance"... Feb 12 20:59:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Feb 12 20:59:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:18 volumio go-librespot[7781]: go-librespot daemon starting... Feb 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20:59:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20:59:18+07:00" level=debug msg="app state loaded" Feb 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20:59:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "network"... Feb 12 20:59:18 volumio volumio[7745]: info: Refreshing Cached IP Addresses Feb 12 20:59:18 volumio sudo[7790]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 20:59:18 volumio sudo[7790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:18 volumio sudo[7790]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:18 volumio sudo[7792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 20:59:18 volumio sudo[7792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "services"... Feb 12 20:59:18 volumio sudo[7792]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "alsa_controller"... Feb 12 20:59:18 volumio sudo[7799]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 20:59:18 volumio sudo[7799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20: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 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20: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 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20: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 12 20:59:18 volumio go-librespot[7782]: time="2026-02-12T20:59:18+07:00" level=info msg="zeroconf server listening on port 41497" Feb 12 20:59:18 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "wizard"... Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "networkfs"... Feb 12 20:59:18 volumio volumio[7745]: info: Starting Udev Watcher for removable devices Feb 12 20:59:18 volumio volumio[7745]: info: Ignoring mount for partition: boot Feb 12 20:59:18 volumio volumio[7745]: info: Ignoring mount for partition: volumio Feb 12 20:59:18 volumio volumio[7745]: info: Ignoring mount for partition: volumio_data Feb 12 20:59:18 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "volumio_command_line_client"... Feb 12 20:59:18 volumio volumio[7745]: info: Loading plugin "upnp"... Feb 12 20:59:19 volumio go-librespot[7782]: time="2026-02-12T20:59:19+07:00" level=debug msg="obtained new client token: AACEhcZa+id7dMlK3CV1AiXRlXOJyEFzWp4XzzWvnQ7IO9XBy87slLkFDokYwsuLkJhM3XHmCjzQVoY5pzNjqYwRmm1uUbU4q7l0tHra3gOLh76VcLr2+XNlhieI9Ow0RoA4YkWjt7Tswh8K5hVmmfqbVi7u8mQdfBugFBBGHyr/TyrrDj7o5K0gS7soUf27cNbJ06d+4eYRKUAueZAc8yaoDmk3Ot62+C3fGh+QKvDqFR0Vvr5H54vYCQ==" Feb 12 20:59:19 volumio volumio[7745]: info: [1770904759015] Starting Upmpd Daemon Feb 12 20:59:19 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 20:59:19 volumio volumio[7745]: info: Loading plugin "my_music"... Feb 12 20:59:19 volumio volumio[7745]: info: Loading plugin "mpd"... Feb 12 20:59:19 volumio go-librespot[7782]: time="2026-02-12T20:59:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:19 volumio go-librespot[7782]: time="2026-02-12T20:59:19+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:19 volumio go-librespot[7782]: time="2026-02-12T20:59:19+07:00" level=debug msg="completed challenge" Feb 12 20:59:19 volumio go-librespot[7782]: time="2026-02-12T20: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 12 20:59:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:19 volumio volumio[7745]: info: Loading plugin "upnp_browser"... Feb 12 20:59:21 volumio sudo[7799]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 12 20:59:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:22 volumio volumio[7745]: info: Starting UPNP Browser Feb 12 20:59:22 volumio volumio[7745]: info: Loading plugin "alarm-clock"... Feb 12 20:59:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:22 volumio go-librespot[7823]: go-librespot daemon starting... Feb 12 20:59:22 volumio go-librespot[7824]: time="2026-02-12T20:59:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:22 volumio go-librespot[7824]: time="2026-02-12T20:59:22+07:00" level=debug msg="app state loaded" Feb 12 20:59:22 volumio go-librespot[7824]: time="2026-02-12T20:59:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:22 volumio volumio[7745]: info: Loading plugin "airplay_emulation"... Feb 12 20:59:22 volumio volumio[7745]: info: Starting Shairport Sync Feb 12 20:59:23 volumio volumio[7745]: info: Loading plugin "last_100"... Feb 12 20:59:23 volumio volumio[7745]: info: Loading plugin "webradio"... Feb 12 20:59:23 volumio volumio[7745]: info: Loading plugin "i2s_dacs"... Feb 12 20:59:23 volumio volumio[7745]: info: Loading plugin "volumiodiscovery"... Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+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 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+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 12 20:59:23 volumio volumio[7745]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 20:59:23 volumio volumio[7745]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 20:59:23 volumio volumio[7745]: *** WARNING *** For more information see Feb 12 20:59:23 volumio volumio[7745]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 20:59:23 volumio volumio[7745]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 20:59:23 volumio volumio[7745]: *** WARNING *** For more information see Feb 12 20:59:23 volumio node[7745]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 20:59:23 volumio node[7745]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 20:59:23 volumio node[7745]: *** WARNING *** For more information see Feb 12 20:59:23 volumio node[7745]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 20:59:23 volumio node[7745]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 20:59:23 volumio node[7745]: *** WARNING *** For more information see Feb 12 20:59:23 volumio volumio[7745]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+07:00" level=info msg="zeroconf server listening on port 33263" Feb 12 20:59:23 volumio volumio[7745]: info: Discovery: Started advertising with name: Volumio Feb 12 20:59:23 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 20:59:23 volumio volumio[7745]: info: Loading plugin "spop"... Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+07:00" level=debug msg="obtained new client token: AAAp+2RxKQscHFgO2o9YB39c5LAOKmsRaZP2COEeaA7b3x2EkLjQsZUq8iBZiCvcmpiC3AwqxtSKdXpqvlfNhvbnjDRXsThQQQ4d+PnQT4bLaJLZH6XKjEfYzBej7elNciJkK0S4aI/gtwVDbSwWp73rXvVkLAnR2LSw2bdKd8jktmnhZY7Z3DRytP8B/HyDynYsVTZMc/Xh27Qo3lu2exvz4ebNEXxX192P9XHttuGsDO0WLFTwK5lgXw==" Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20:59:23+07:00" level=debug msg="completed challenge" Feb 12 20:59:23 volumio go-librespot[7824]: time="2026-02-12T20: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 12 20:59:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:25 volumio volumio[7745]: info: Loading plugin "ytcr"... Feb 12 20:59:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 12 20:59:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:27 volumio go-librespot[7835]: go-librespot daemon starting... Feb 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20:59:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20:59:27+07:00" level=debug msg="app state loaded" Feb 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20:59:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20: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 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20: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 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20: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 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20:59:27+07:00" level=info msg="zeroconf server listening on port 42557" Feb 12 20:59:27 volumio volumio[7745]: info: Loading plugin "ytmusic"... Feb 12 20:59:27 volumio go-librespot[7836]: time="2026-02-12T20:59:27+07:00" level=debug msg="obtained new client token: AABGdnUgd0xckXaM3/PO4M7zNM1e7rYsrub0AD6WA7+8emdN/kvA4aN3dzsJAZS6SOeiyZ/I5Tx6xp20thsVPHjA+39voVqi0+b8J/qG8gE2kl34c01Pv9XRqIKA17M4MgBR1NNIs2hGSzoGui0QMp9b+pmy3RdyAg1F6me/h9PMbw0OnEDDdmokMfK17GcXpjoWUuWlmcc7LuvtTXqh7dzkHuqwqrTnDfJggx+gpW2sO+e8U1fNlmkkTA==" Feb 12 20:59:28 volumio go-librespot[7836]: time="2026-02-12T20:59:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:28 volumio go-librespot[7836]: time="2026-02-12T20:59:28+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:28 volumio go-librespot[7836]: time="2026-02-12T20:59:28+07:00" level=debug msg="completed challenge" Feb 12 20:59:28 volumio go-librespot[7836]: time="2026-02-12T20:59:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 20:59:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:28 volumio volumio-remote-updater[643]: [2026-02-12 20:59:28] [connect] Successful connection Feb 12 20:59:28 volumio volumio[7745]: info: Loading plugin "outputs"... Feb 12 20:59:28 volumio volumio[7745]: info: Loading plugin "albumart"... Feb 12 20:59:29 volumio volumio[7745]: info: Plugin example_plugin is not enabled Feb 12 20:59:29 volumio volumio[7745]: info: Loading plugin "inputs"... Feb 12 20:59:29 volumio volumio[7745]: info: Loading plugin "updater_comm"... Feb 12 20:59:29 volumio volumio[7745]: info: Plugin mpdemulation is not enabled Feb 12 20:59:29 volumio volumio[7745]: info: Loading plugin "rest_api"... Feb 12 20:59:29 volumio volumio[7745]: info: Loading plugin "websocket"... Feb 12 20:59:29 volumio volumio[7745]: info: Starting Socket.io Server version 1.7.4 Feb 12 20:59:29 volumio volumio[7745]: info: Loading plugin "RoonBridge"... Feb 12 20:59:29 volumio volumio[7745]: info: Applying required configuration parameters for plugin RoonBridge Feb 12 20:59:29 volumio volumio[7745]: info: Loading i18n strings for locale en Feb 12 20:59:29 volumio volumio[7745]: Updating browse sources language Feb 12 20:59:29 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:59:30 volumio volumio[7858]: Forking 3 albumart workers Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::initPlayerControls Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: Express server listening on port 3000 Feb 12 20:59:30 volumio volumio[7745]: [Metrics] WebUI: 16s 103.12ms Feb 12 20:59:30 volumio volumio[7745]: info: CoreStateMachine::resetVolumioState Feb 12 20:59:30 volumio volumio[7745]: info: CoreStateMachine::getcurrentVolume Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 20:59:30 volumio sudo[7901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 20:59:30 volumio sudo[7901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:30 volumio sudo[7904]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 20:59:30 volumio sudo[7901]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:30 volumio sudo[7904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:30 volumio volumio[7745]: info: Volumio Network Manager: Network status updated: 1 Feb 12 20:59:30 volumio sudo[7904]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:30 volumio volumio[7745]: info: VolumeController:: Volume=100 Mute =false Feb 12 20:59:30 volumio volumio[7745]: info: CoreStateMachine::pushState Feb 12 20:59:30 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::volumioPushState Feb 12 20:59:30 volumio volumio[7745]: info: CoreStateMachine::updateTrackBlock Feb 12 20:59:30 volumio volumio[7745]: info: CorePlayQueue::getTrackBlock Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 20:59:30 volumio volumio-remote-updater[643]: [2026-02-12 20:59:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770904768 101 Feb 12 20:59:30 volumio volumio[7745]: 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 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:30 volumio volumio[7745]: info: Reloading queue from file Feb 12 20:59:30 volumio volumio[7745]: info: CoreStateMachine::setRepeat null single undefined Feb 12 20:59:30 volumio volumio[7745]: info: CoreStateMachine::pushState Feb 12 20:59:30 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 20:59:30 volumio volumio[7745]: info: CoreCommandRouter::volumioPushState Feb 12 20:59:31 volumio volumio[7745]: info: CoreStateMachine::setRandom null Feb 12 20:59:31 volumio volumio[7745]: info: CoreStateMachine::pushState Feb 12 20:59:31 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::volumioPushState Feb 12 20:59:31 volumio volumio[7745]: info: Setting Device type: Raspberry PI Feb 12 20:59:31 volumio volumio[7745]: info: Completed loading Core Plugins Feb 12 20:59:31 volumio volumio[7745]: info: Preparing to generate the ALSA configuration file Feb 12 20:59:31 volumio volumio[7745]: info: VolumeController:: Volume=100 Mute =false Feb 12 20:59:31 volumio volumio[7745]: info: CoreStateMachine::pushState Feb 12 20:59:31 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::volumioPushState Feb 12 20:59:31 volumio volumio[7745]: info: Asound.conf file unchanged, so no further update is needed Feb 12 20:59:31 volumio volumio[7745]: info: Output device has changed, restarting MPD Feb 12 20:59:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 12 20:59:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:31 volumio volumio[7745]: info: Output device has changed, restarting Shairport Sync Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:31 volumio sudo[7919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 20:59:31 volumio sudo[7919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:31 volumio go-librespot[7922]: go-librespot daemon starting... Feb 12 20:59:31 volumio sudo[7921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 20:59:31 volumio sudo[7921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:31 volumio volumio[7745]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 20:59:31 volumio sudo[7921]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:31 volumio volumio[7745]: info: ___________ START PLUGINS ___________ Feb 12 20:59:31 volumio go-librespot[7930]: time="2026-02-12T20:59:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:31 volumio go-librespot[7930]: time="2026-02-12T20:59:31+07:00" level=debug msg="app state loaded" Feb 12 20:59:31 volumio sudo[7924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 20:59:31 volumio sudo[7924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:31 volumio go-librespot[7930]: time="2026-02-12T20:59:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:31 volumio volumio[7745]: info: ControllerMpd::onStart: Initializing MPD Feb 12 20:59:31 volumio volumio[7745]: info: Creating MPD Configuration file Feb 12 20:59:31 volumio sudo[7919]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 20:59:31 volumio volumio[7745]: info: [1770904771610] CoreMusicLibrary::Adding element Media Servers Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:59:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 20:59:31 volumio sudo[7940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 20:59:31 volumio sudo[7940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:31 volumio sudo[7942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 20:59:31 volumio sudo[7942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:31 volumio sudo[7940]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:31 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 20:59:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 20:59:31 volumio systemd[1]: mpd.service: Consumed 7.054s CPU time. Feb 12 20:59:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 20:59:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 20:59:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 20:59:31 volumio volumio[7745]: info: UPNP Browser: Client initialized successfully Feb 12 20:59:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:31 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 20:59:32 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 20:59:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 20:59:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 20:59:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 20:59:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 20:59:32 volumio volumio[7745]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 20:59:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 20:59:32 volumio volumio[7745]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 20:59:32 volumio volumio[7745]: info: [1770904772164] CoreMusicLibrary::Adding element Last_100 Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 20:59:32 volumio volumio[7745]: info: [1770904772175] CoreMusicLibrary::Adding element Webradio Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 20:59:32 volumio volumio[7745]: info: Initializing BBC Radios Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+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 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+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 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+07:00" level=info msg="zeroconf server listening on port 46423" Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:32 volumio volumio[7745]: info: Creating Spotify config file Feb 12 20:59:32 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+07:00" level=debug msg="obtained new client token: AABv6VEvEsDqa4vB0bj0GFo29Oqk8xQc2PiL8VPwWbXoNmCO6qvZ32vmaYXm247wT7IShp7ou/KsX/2vR3RDl4dWGHwYLe+kUIRKyYLjceQF+/SgJJCepaT4Pds5YZdbtyEftUbFd9XU+orWJG+flY47lX8Js7ogspPOnPgdLYYGMa1fz1zNfwdbNMJZqz82SfXO5CL9bmZdcuOEPEEzvX6MdBzwk6+XRp6cbUh3X8yyEonmXuTWJJawOA==" Feb 12 20:59:32 volumio sudo[7955]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 20:59:32 volumio sudo[7955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 20:59:32 volumio sudo[7955]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20:59:32+07:00" level=debug msg="completed challenge" Feb 12 20:59:32 volumio go-librespot[7930]: time="2026-02-12T20: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 12 20:59:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:33 volumio volumio[7868]: Starting albumart workers Feb 12 20:59:33 volumio volumio[7745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 20:59:33 volumio volumio[7745]: info: [1770904773544] CoreMusicLibrary::Adding element YouTube Music Feb 12 20:59:33 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:59:33 volumio volumio[7745]: Cannot find translation for source YouTube Music Feb 12 20:59:33 volumio volumio[7745]: info: Volumio Calling Home Feb 12 20:59:33 volumio sudo[7974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 12 20:59:33 volumio sudo[7974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:33 volumio volumio[7869]: Starting albumart workers Feb 12 20:59:33 volumio sudo[7974]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:33 volumio volumio[7870]: Starting albumart workers Feb 12 20:59:34 volumio volumio[7745]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 20:59:34 volumio volumio[7745]: info: Discovery: Found device Volumio Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::volumioGetState Feb 12 20:59:34 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:34 volumio volumio[7745]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 20:59:34 volumio volumio[7745]: info: Discovery: Found device Volumio Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::volumioGetState Feb 12 20:59:34 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:34 volumio volumio[7745]: info: MPD Permissions set Feb 12 20:59:34 volumio volumio[7745]: info: MPD Permissions set Feb 12 20:59:34 volumio volumio[7745]: info: Upmpdcli Daemon Started Feb 12 20:59:34 volumio volumio[7745]: info: Spotify config file written Feb 12 20:59:34 volumio volumio[7745]: 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 12 20:59:34 volumio volumio[7745]: info: Volumio called home Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio sudo[7980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 20:59:34 volumio sudo[7980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 20:59:34 volumio volumio[7745]: info: No need to fix Spotify hosts Feb 12 20:59:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:35 volumio go-librespot[7992]: go-librespot daemon starting... Feb 12 20:59:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:35 volumio sudo[7980]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+07:00" level=debug msg="app state loaded" Feb 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+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 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+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 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+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 12 20:59:35 volumio go-librespot[7993]: time="2026-02-12T20:59:35+07:00" level=info msg="zeroconf server listening on port 43967" Feb 12 20:59:36 volumio go-librespot[7993]: time="2026-02-12T20:59:36+07:00" level=debug msg="obtained new client token: AAAnqTDcTffZIE87nKj2+i1ocInsgcdvgGNX8mtwpKAeIVak4/eXZYuu+eRT3OOkNlmFisL0J/QPlxUWc4PV9Rh0vU0zs5UNDV9UVmGGG9zTdsx9+HLBae1E8CLFPaBawhCT5nY4mwOcpiyf8OqR9UkYcBK1N+eIClTthOY5M+6PYwED+Wu2RyttnEQ3nnvnid0JpP+O8WEv7ilqDMMPN4UgR/7HqminzVPfQa5mn8pwjMssu3scMXs=" Feb 12 20:59:36 volumio go-librespot[7993]: time="2026-02-12T20:59:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:36 volumio volumio[7745]: info: Starting Shairport Sync Feb 12 20:59:36 volumio volumio[7745]: info: Starting Shairport Sync Feb 12 20:59:36 volumio go-librespot[7993]: time="2026-02-12T20:59:36+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:36 volumio go-librespot[7993]: time="2026-02-12T20:59:36+07:00" level=debug msg="completed challenge" Feb 12 20:59:36 volumio volumio[7745]: info: Starting Shairport Sync Feb 12 20:59:36 volumio sudo[8019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 20:59:36 volumio sudo[8019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:36 volumio sudo[8021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 20:59:36 volumio sudo[8021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:36 volumio go-librespot[7993]: time="2026-02-12T20:59:36+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 12 20:59:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:36 volumio sudo[8024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 20:59:36 volumio sudo[8024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:36 volumio volumio[7745]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 20:59:36 volumio volumio[7745]: SPOTIFY: BQCmmpIT1pFUFC_PIh6hep6h8cwAD-w9QFWK0IVJoZLqA3_ZFlzhRc1b-XUCHb7KaqAH0mL01nQMVkfUXR77f9e58rv_EuHigbQq5YCJ5bOkk8EO3oDHmQP7W5KmC0JYWJi9UCrU9XLOyrM1X4U8ZN9F2mnJhRwuk5qlSDH3jGQAfr6gAwVjHtvmzQ-2aSGGxUMe4VF5WPHP8ARgkRGY3-n1seVDtHEt-5TMpfutoFUx7Ulfwb0QUrMO5iwUacKjRX1P0zsnlQ4Hl4DwQT2IJJI2nqd_nCIadGU_fNcBsJYtL_WM_IG_O7dY Feb 12 20:59:36 volumio volumio[7745]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 20:59:36 volumio volumio[7745]: info: New Spotify access token = BQCmmpIT1pFUFC_PIh6hep6h8cwAD-w9QFWK0IVJoZLqA3_ZFlzhRc1b-XUCHb7KaqAH0mL01nQMVkfUXR77f9e58rv_EuHigbQq5YCJ5bOkk8EO3oDHmQP7W5KmC0JYWJi9UCrU9XLOyrM1X4U8ZN9F2mnJhRwuk5qlSDH3jGQAfr6gAwVjHtvmzQ-2aSGGxUMe4VF5WPHP8ARgkRGY3-n1seVDtHEt-5TMpfutoFUx7Ulfwb0QUrMO5iwUacKjRX1P0zsnlQ4Hl4DwQT2IJJI2nqd_nCIadGU_fNcBsJYtL_WM_IG_O7dY Feb 12 20:59:36 volumio volumio[7745]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 20:59:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 20:59:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 20:59:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 20:59:36 volumio systemd[1]: shairport-sync.service: Consumed 2.215s CPU time. Feb 12 20:59:36 volumio volumio[7745]: info: CoreCommandRouter::volumioGetState Feb 12 20:59:36 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 20:59:36 volumio sudo[8019]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:36 volumio sudo[8021]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:36 volumio sudo[8024]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:36 volumio volumio[7745]: info: Shairport-Sync Started Feb 12 20:59:36 volumio volumio[7745]: Error adding Membership: Error: addMembership EINVAL Feb 12 20:59:36 volumio volumio[7745]: info: Shairport-Sync Started Feb 12 20:59:36 volumio volumio[7745]: info: Shairport-Sync Started Feb 12 20:59:37 volumio volumio[7745]: 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 12 20:59:37 volumio volumio[7745]: info: Spotify Successfully logged in Feb 12 20:59:37 volumio volumio[7745]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 20:59:37 volumio volumio[7745]: info: [1770904777095] CoreMusicLibrary::Adding element Spotify Feb 12 20:59:37 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 20:59:37 volumio volumio[7745]: Cannot find translation for source YouTube Music Feb 12 20:59:37 volumio volumio[7745]: Cannot find translation for source Spotify Feb 12 20:59:37 volumio volumio[7745]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 12 20:59:37 volumio volumio[7745]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 20:59:38 volumio volumio[7745]: info: VolumeController:: Volume=100 Mute =false Feb 12 20:59:38 volumio volumio[7745]: info: CoreCommandRouter::volumioGetState Feb 12 20:59:38 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:38 volumio volumio[7745]: info: CoreStateMachine::pushState Feb 12 20:59:38 volumio volumio[7745]: info: CorePlayQueue::getTrack 0 Feb 12 20:59:38 volumio volumio[7745]: info: CoreCommandRouter::volumioPushState Feb 12 20:59:39 volumio volumio[7745]: info: go-librespot daemon successfully initialized Feb 12 20:59:39 volumio mpd[7972]: 2026-02-12T20:59:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 20:59:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 20:59:39 volumio sudo[7942]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:39 volumio sudo[7924]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 12 20:59:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:39 volumio go-librespot[8063]: go-librespot daemon starting... Feb 12 20:59:39 volumio go-librespot[8064]: time="2026-02-12T20:59:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:39 volumio go-librespot[8064]: time="2026-02-12T20:59:39+07:00" level=debug msg="app state loaded" Feb 12 20:59:39 volumio go-librespot[8064]: time="2026-02-12T20:59:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:39 volumio volumio[7745]: error: MPD error: The expression evaluated to a falsy value: Feb 12 20:59:39 volumio volumio[7745]: assert.ok(self.idling) Feb 12 20:59:39 volumio volumio[7745]: error: The expression evaluated to a falsy value: Feb 12 20:59:39 volumio volumio[7745]: assert.ok(self.idling) Feb 12 20:59:39 volumio volumio[7745]: error: updateQueue error: null Feb 12 20:59:39 volumio volumio[7745]: info: MPD running with PID7972 Feb 12 20:59:39 volumio volumio[7745]: ,establishing connection Feb 12 20:59:39 volumio volumio[7745]: info: Completed starting Core Plugins Feb 12 20:59:39 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:39 volumio volumio[7745]: info: ----- MyVolumio plugins startup ---- Feb 12 20:59:39 volumio volumio[7745]: info: ------------------------------------------- Feb 12 20:59:39 volumio volumio[7745]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 20:59:39 volumio volumio[7745]: error: updateQueue error: null Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=info msg="zeroconf server listening on port 44035" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="obtained new client token: AADioqVjOF9BQ8Y6Rqh4odwavF6nVQ8jSmXD4JRQVX8lXeR5UNdsl9Xj0Ow12ZdhKuUIx1fbOuse33IFgextCyiHqbfKnVNu+lG0ajwauaFvT4q3i46OSa8GSjSzK3UnLRXIkuOOZg0++qKs6mPZRoSuY4fBxNPXIumeRospYvM7HvgeUWbWBE2w7+Yiu45/ybA69MIO71uElL5G4Kcy1OBAS9j6bRLvJGqRirmnDrjdbX/byE0x9f2pFQ==" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+07:00" level=debug msg="completed challenge" Feb 12 20:59:40 volumio go-librespot[8064]: time="2026-02-12T20:59:40+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 12 20:59:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:42 volumio volumio[7745]: info: Initializing connection to go-librespot Websocket Feb 12 20:59:42 volumio volumio[7745]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:59:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 12 20:59:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:43 volumio go-librespot[8071]: go-librespot daemon starting... Feb 12 20:59:43 volumio go-librespot[8072]: time="2026-02-12T20:59:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:43 volumio go-librespot[8072]: time="2026-02-12T20:59:43+07:00" level=debug msg="app state loaded" Feb 12 20:59:43 volumio go-librespot[8072]: time="2026-02-12T20:59:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+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 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+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 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=info msg="zeroconf server listening on port 37537" Feb 12 20:59:44 volumio volumio[7745]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=debug msg="obtained new client token: AABlEjvPoAqu04LTg1kSpOkCy31RvLt44uhEEQrFaZasdFEgtfW7B6wK+Gur0cDXOG9Xeb+cPu5VEzD8aG21fF9PBcNRId5rbNLCvjdAtHRMT9qhQUpilzzHSeoRE/dfU38Yx5HYq4hAsJgU7a5Lys1rqY1RwA5rwqfsEFeCk2VOBHNvA4P9rJi0SoDWYqsT3p0HYARjw2gTTWkpcVQcoGq0j/voxzd5GCb9wsxHznLFDgYxnQcttpue+A==" Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=debug msg="completed challenge" Feb 12 20:59:44 volumio go-librespot[8072]: time="2026-02-12T20:59:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 20:59:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:45 volumio volumio[7745]: info: Initializing connection to go-librespot Websocket Feb 12 20:59:45 volumio volumio[7745]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:59:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 12 20:59:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:48 volumio go-librespot[8079]: go-librespot daemon starting... Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=debug msg="app state loaded" Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:48 volumio volumio[7745]: info: Initializing connection to go-librespot Websocket Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=debug msg="new websocket client" Feb 12 20:59:48 volumio volumio[7745]: info: Connection to go-librespot Websocket established Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin bluetooth to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin multiroom to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin metavolumio to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin cd_controller to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 12 20:59:48 volumio volumio[7745]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=info msg="zeroconf server listening on port 34203" Feb 12 20:59:48 volumio go-librespot[8080]: time="2026-02-12T20:59:48+07:00" level=debug msg="obtained new client token: AABrZwkZonkXj6nAzDjbuscyMUvwTd3GUZwLVqdpNM7OQCwNsTFQVaaWQGxV02BGURBDY38l+mesIKDSeIF8Lzprr+xugSTFdZjmgoF3Enw8JpbGOuSf6bI6bPAo+9OghOEvcyUY3IN1rlyn2Tsk49CLGr6Q3GKXuMrr55wIUOhqKjzr6bjWYn/uNopvV5ZJobxpFJY5SJbqYeAhkWQbYUzaDF9t0S/Zx2pIJmooazpdwDMM4667aRDHeA==" Feb 12 20:59:49 volumio go-librespot[8080]: time="2026-02-12T20:59:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:49 volumio go-librespot[8080]: time="2026-02-12T20:59:49+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:49 volumio go-librespot[8080]: time="2026-02-12T20:59:49+07:00" level=debug msg="completed challenge" Feb 12 20:59:49 volumio go-librespot[8080]: time="2026-02-12T20:59:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 20:59:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:50 volumio volumio[7745]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 20:59:50 volumio volumio[7745]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 20:59:50 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:50 volumio volumio[7745]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 20:59:50 volumio volumio[7745]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 20:59:50 volumio volumio[7745]: info: MyVolumio login type: Token Feb 12 20:59:50 volumio volumio[7745]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 20:59:50 volumio volumio[7745]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 20:59:52 volumio volumio[7745]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 20:59:52 volumio volumio[7745]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 20:59:52 volumio volumio[7745]: info: Streaming services startup Feb 12 20:59:52 volumio volumio[7745]: info: Starting Streaming Daemon Feb 12 20:59:52 volumio sudo[8102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 20:59:52 volumio sudo[8102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:52 volumio volumio[7745]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 20:59:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 12 20:59:52 volumio sudo[8102]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:52 volumio volumio[7745]: info: Getting Spotify volume Feb 12 20:59:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:52 volumio go-librespot[8108]: go-librespot daemon starting... Feb 12 20:59:52 volumio volumio[7745]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:59:52 volumio volumio[7745]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 20:59:52 volumio volumio[7745]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 20:59:52 volumio volumio[7745]: errno: -111, Feb 12 20:59:52 volumio volumio[7745]: code: 'ECONNREFUSED', Feb 12 20:59:52 volumio volumio[7745]: syscall: 'connect', Feb 12 20:59:52 volumio volumio[7745]: address: '127.0.0.1', Feb 12 20:59:52 volumio volumio[7745]: port: 9879, Feb 12 20:59:52 volumio volumio[7745]: response: undefined Feb 12 20:59:52 volumio volumio[7745]: } Feb 12 20:59:52 volumio volumio[7745]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 20:59:52 volumio go-librespot[8109]: time="2026-02-12T20:59:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:52 volumio go-librespot[8109]: time="2026-02-12T20:59:52+07:00" level=debug msg="app state loaded" Feb 12 20:59:52 volumio go-librespot[8109]: time="2026-02-12T20:59:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:52 volumio go-librespot[8109]: time="2026-02-12T20:59:52+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 20:59:52 volumio go-librespot[8109]: time="2026-02-12T20:59:52+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 12 20:59:52 volumio go-librespot[8109]: time="2026-02-12T20:59:52+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 12 20:59:53 volumio go-librespot[8109]: time="2026-02-12T20:59:53+07:00" level=info msg="zeroconf server listening on port 36641" Feb 12 20:59:53 volumio go-librespot[8109]: time="2026-02-12T20:59:53+07:00" level=debug msg="obtained new client token: AAAuCHp/VGfErpr4GrNOjemZqS2LH1RduC92OJJl6gZ1WTBYEGG4zW8P965CM+LRBBxMS8tgpGv6B1gYchayKymqQWB75Zvqi3TX+BRb+rsXWtXjboVrS0VnujoJf0Rk3Ks0PMdCiHv60Ey0dZhffWFr+gLYcgIfe7g2l/ooMevbBRRNLLfFl8/XpaDkyF9l2VZwuGJGtsGR9trumuDpZ9up/QaZRfrLr42HDaLyqyrFKGvXKNBViEw=" Feb 12 20:59:53 volumio go-librespot[8109]: time="2026-02-12T20:59:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:53 volumio go-librespot[8109]: time="2026-02-12T20:59:53+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:53 volumio go-librespot[8109]: time="2026-02-12T20:59:53+07:00" level=debug msg="completed challenge" Feb 12 20:59:53 volumio go-librespot[8109]: time="2026-02-12T20:59:53+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 12 20:59:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:53 volumio sudo[8131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 20:58' Feb 12 20:59:53 volumio sudo[8131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 20:59:54 volumio sudo[8131]: pam_unix(sudo:session): session closed for user root Feb 12 20:59:54 volumio volumio-remote-updater[643]: [2026-02-12 20:59:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 12 20:59:54 volumio volumio-remote-updater[643]: [2026-02-12 20:59:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 12 20:59:54 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:54 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 20:59:54 volumio systemd[1]: volumio.service: Consumed 54.862s CPU time. Feb 12 20:59:54 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 20:59:54 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 20:59:54 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13748. Feb 12 20:59:54 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 20:59:54 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 20:59:54 volumio systemd[1]: volumio.service: Consumed 54.862s CPU time. Feb 12 20:59:54 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 20:59:54 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 20:59:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 12 20:59:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 20:59:56 volumio go-librespot[8161]: go-librespot daemon starting... Feb 12 20:59:56 volumio go-librespot[8162]: time="2026-02-12T20:59:56+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 20:59:56 volumio go-librespot[8162]: time="2026-02-12T20:59:56+07:00" level=debug msg="app state loaded" Feb 12 20:59:56 volumio go-librespot[8162]: time="2026-02-12T20:59:56+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+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 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+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 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+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 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+07:00" level=info msg="zeroconf server listening on port 37491" Feb 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+07:00" level=debug msg="obtained new client token: AAB/nERt1J4VBIh948GSR98EfD4Rb5ZKePcq4ShNpBhbnaDdoVyIg3tt+oAlPDAbAHnl4uPV2wzCQmN50yASJuugE6GCk9UOVSQm7vo+KJoaLtJYQxMm+KPHN5nkHV6Dio2GkPNSmUm34ESilhjO6tLegfHVcn1EdIoNyCwchaDchd+rREMAPWpvM66LmAz0A4pEcJzdfa9LYllEaodLZNXnVMT+xb0NDWvC0AWfUaXNeY431C2zwyLubw==" Feb 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+07:00" level=debug msg="completed keyexchange" Feb 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+07:00" level=debug msg="completed challenge" Feb 12 20:59:57 volumio go-librespot[8162]: time="2026-02-12T20:59:57+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 12 20:59:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 20:59:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 20:59:58 volumio volumio[8145]: info: ------------------------------------------- Feb 12 20:59:58 volumio volumio[8145]: info: ----- Volumio3 ---- Feb 12 20:59:58 volumio volumio[8145]: info: ------------------------------------------- Feb 12 20:59:58 volumio volumio[8145]: info: ----- System startup ---- Feb 12 20:59:58 volumio volumio[8145]: info: ------------------------------------------- Feb 12 20:59:59 volumio volumio-remote-updater[643]: [2026-02-12 20:59:59] [connect] Successful connection Feb 12 20:59:59 volumio volumio[8145]: info: MYVOLUMIO Environment detected Feb 12 20:59:59 volumio volumio[8145]: info: Plugin folders cleanup Feb 12 20:59:59 volumio volumio[8145]: info: Scanning into folder /volumio/app/plugins/ Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category audio_interface Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category miscellanea Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category music_service Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category plugins.json Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category system_controller Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category user_interface Feb 12 20:59:59 volumio volumio[8145]: info: Scanning into folder /data/plugins/ Feb 12 20:59:59 volumio volumio[8145]: info: Scanning category music_service Feb 12 20:59:59 volumio volumio[8145]: info: Plugin folders cleanup completed Feb 12 20:59:59 volumio volumio[8145]: info: ------------------------------------------- Feb 12 20:59:59 volumio volumio[8145]: info: ----- Core plugins startup ---- Feb 12 20:59:59 volumio volumio[8145]: info: ------------------------------------------- Feb 12 20:59:59 volumio volumio[8145]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 20:59:59 volumio volumio[8145]: info: Adding plugin upnp to MyMusic Plugins Feb 12 20:59:59 volumio volumio[8145]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 20:59:59 volumio volumio[8145]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 20:59:59 volumio volumio[8145]: info: Loading plugins from folder /data/plugins/ Feb 12 20:59:59 volumio volumio[8145]: info: Loading plugin "system"... Feb 12 20:59:59 volumio volumio[8145]: info: Loading plugin "appearance"... Feb 12 21:00:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 12 21:00:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:01 volumio go-librespot[8182]: go-librespot daemon starting... Feb 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+07:00" level=debug msg="app state loaded" Feb 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+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 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+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 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+07:00" level=info msg="zeroconf server listening on port 35549" Feb 12 21:00:01 volumio volumio[8145]: info: Loading plugin "network"... Feb 12 21:00:01 volumio volumio[8145]: info: Refreshing Cached IP Addresses Feb 12 21:00:01 volumio sudo[8191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 21:00:01 volumio sudo[8191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:01 volumio sudo[8193]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 21:00:01 volumio sudo[8191]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:01 volumio sudo[8193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:01 volumio sudo[8193]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:01 volumio volumio[8145]: info: Loading plugin "services"... Feb 12 21:00:01 volumio volumio[8145]: info: Loading plugin "alsa_controller"... Feb 12 21:00:01 volumio sudo[8201]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 21:00:01 volumio sudo[8201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:01 volumio go-librespot[8183]: time="2026-02-12T21:00:01+07:00" level=debug msg="obtained new client token: AAD3ygRryhAMWvIf3SDVBtlGvvy5EgU2vle61dyC3963+XilhcCl6MO4tvCX69WWIKUhoJhTXaxz3mYkCOp7Sj/5nKw1fc53ftm2AmmsdQl8XARxtthHONG8sOufEYrOv5HqcSJEKlt/aJsghyviA14JSC1ZH3kJll66rvrz3fPf4Dxb4PVWkxEsUIaswjxM24/qEfMvsfIXWlEYwteoZgyomfElbegrBHg4OZlNxt411q5LaWHJ0r0prg==" Feb 12 21:00:02 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 21:00:02 volumio volumio[8145]: info: Loading plugin "wizard"... Feb 12 21:00:02 volumio go-librespot[8183]: time="2026-02-12T21:00:02+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 12 21:00:02 volumio volumio[8145]: info: Loading plugin "networkfs"... Feb 12 21:00:02 volumio volumio[8145]: info: Starting Udev Watcher for removable devices Feb 12 21:00:02 volumio go-librespot[8183]: time="2026-02-12T21:00:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 12 21:00:02 volumio volumio[8145]: info: Ignoring mount for partition: boot Feb 12 21:00:02 volumio volumio[8145]: info: Ignoring mount for partition: volumio Feb 12 21:00:02 volumio volumio[8145]: info: Ignoring mount for partition: volumio_data Feb 12 21:00:02 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 21:00:02 volumio volumio[8145]: info: Loading plugin "volumio_command_line_client"... Feb 12 21:00:02 volumio volumio[8145]: info: Loading plugin "upnp"... Feb 12 21:00:02 volumio volumio[8145]: info: [1770904802191] Starting Upmpd Daemon Feb 12 21:00:02 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 21:00:02 volumio volumio[8145]: info: Loading plugin "my_music"... Feb 12 21:00:02 volumio volumio[8145]: info: Loading plugin "mpd"... Feb 12 21:00:02 volumio go-librespot[8183]: time="2026-02-12T21:00:02+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:02 volumio go-librespot[8183]: time="2026-02-12T21:00:02+07:00" level=debug msg="completed challenge" Feb 12 21:00:02 volumio go-librespot[8183]: time="2026-02-12T21:00:02+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 12 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:02 volumio volumio[8145]: info: Loading plugin "upnp_browser"... Feb 12 21:00:04 volumio sudo[8201]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 12 21:00:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:05 volumio go-librespot[8224]: go-librespot daemon starting... Feb 12 21:00:05 volumio go-librespot[8225]: time="2026-02-12T21:00:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:05 volumio go-librespot[8225]: time="2026-02-12T21:00:05+07:00" level=debug msg="app state loaded" Feb 12 21:00:05 volumio go-librespot[8225]: time="2026-02-12T21:00:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:05 volumio volumio[8145]: info: Starting UPNP Browser Feb 12 21:00:05 volumio volumio[8145]: info: Loading plugin "alarm-clock"... Feb 12 21:00:06 volumio volumio[8145]: info: Loading plugin "airplay_emulation"... Feb 12 21:00:06 volumio volumio[8145]: info: Starting Shairport Sync Feb 12 21:00:06 volumio volumio[8145]: info: Loading plugin "last_100"... Feb 12 21:00:06 volumio volumio[8145]: info: Loading plugin "webradio"... Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+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 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+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 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+07:00" level=info msg="zeroconf server listening on port 37111" Feb 12 21:00:06 volumio volumio[8145]: info: Loading plugin "i2s_dacs"... Feb 12 21:00:06 volumio volumio[8145]: info: Loading plugin "volumiodiscovery"... Feb 12 21:00:06 volumio volumio[8145]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 21:00:06 volumio volumio[8145]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 21:00:06 volumio volumio[8145]: *** WARNING *** For more information see Feb 12 21:00:06 volumio volumio[8145]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 21:00:06 volumio volumio[8145]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 21:00:06 volumio volumio[8145]: *** WARNING *** For more information see Feb 12 21:00:06 volumio node[8145]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 21:00:06 volumio node[8145]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 21:00:06 volumio node[8145]: *** WARNING *** For more information see Feb 12 21:00:06 volumio node[8145]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 21:00:06 volumio node[8145]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 21:00:06 volumio node[8145]: *** WARNING *** For more information see Feb 12 21:00:06 volumio volumio[8145]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 21:00:06 volumio volumio[8145]: info: Discovery: Started advertising with name: Volumio Feb 12 21:00:06 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 21:00:06 volumio volumio[8145]: info: Loading plugin "spop"... Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+07:00" level=debug msg="obtained new client token: AABYUmQ0XSK3JbJobkYlF7J9/WiGAX72YbwQTQfAfLeGAfDD9rbUhoStewXuYMF4nqzpTol0cQ5Jwum5ygpMDExvsLMXsybExhC06NE8fviNZVUaiUCiEpAAMLgzLAiuFp/SYkBdd7A9cYbQ8/B2YjvDVg6alA4QJBaUIDvG9lOpKXKBXhS10VcVe8L+YRpe5KgkdWSfCEOgilqaaQE+mFcYjowgASGbBvlXulIAJMU6k1B9r11zSPIcHA==" Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00:06+07:00" level=debug msg="completed challenge" Feb 12 21:00:06 volumio go-librespot[8225]: time="2026-02-12T21:00: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 12 21:00:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:08 volumio volumio[8145]: info: Loading plugin "ytcr"... Feb 12 21:00:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 12 21:00:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:10 volumio go-librespot[8235]: go-librespot daemon starting... Feb 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+07:00" level=debug msg="app state loaded" Feb 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+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 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+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 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+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 12 21:00:10 volumio go-librespot[8236]: time="2026-02-12T21:00:10+07:00" level=info msg="zeroconf server listening on port 34983" Feb 12 21:00:11 volumio volumio[8145]: info: Loading plugin "ytmusic"... Feb 12 21:00:11 volumio go-librespot[8236]: time="2026-02-12T21:00:11+07:00" level=debug msg="obtained new client token: AAAs9p6uI9V/Tap1vt9hNvzRQaBuQy4Xx2XEV5Arf3vpzKp/uOtiOTPJ1vqbqC7KSliU6lQKQmeYtM0pByGpi1l1oM3tdT0WJifOpwIrq+IO9tCtWSCeW6vNg6FJK0qby5Xhl7DcDXEt0mwHRrPaAdEtdxkNrBnrNM8twULGT08oav1kcMJRR2Cr6athDPI62Mz6ZRJq4SWmg9Jx9LksZfNM3K9iJwId+sF4iNCmA2y2hcJrfPj77QQ=" Feb 12 21:00:11 volumio go-librespot[8236]: time="2026-02-12T21:00:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 21:00:11 volumio go-librespot[8236]: time="2026-02-12T21:00:11+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:11 volumio go-librespot[8236]: time="2026-02-12T21:00:11+07:00" level=debug msg="completed challenge" Feb 12 21:00:11 volumio go-librespot[8236]: time="2026-02-12T21:00:11+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 21:00:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:11 volumio volumio-remote-updater[643]: [2026-02-12 21:00:11] [connect] Successful connection Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "outputs"... Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "albumart"... Feb 12 21:00:12 volumio volumio[8145]: info: Plugin example_plugin is not enabled Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "inputs"... Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "updater_comm"... Feb 12 21:00:12 volumio volumio[8145]: info: Plugin mpdemulation is not enabled Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "rest_api"... Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "websocket"... Feb 12 21:00:12 volumio volumio[8145]: info: Starting Socket.io Server version 1.7.4 Feb 12 21:00:12 volumio volumio[8145]: info: Loading plugin "RoonBridge"... Feb 12 21:00:12 volumio volumio[8145]: info: Applying required configuration parameters for plugin RoonBridge Feb 12 21:00:12 volumio volumio[8145]: info: Loading i18n strings for locale en Feb 12 21:00:12 volumio volumio[8145]: Updating browse sources language Feb 12 21:00:12 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 21:00:13 volumio volumio[8258]: Forking 3 albumart workers Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::initPlayerControls Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 21:00:13 volumio volumio[8145]: Express server listening on port 3000 Feb 12 21:00:13 volumio volumio[8145]: [Metrics] WebUI: 16s 23.75ms Feb 12 21:00:13 volumio volumio[8145]: info: CoreStateMachine::resetVolumioState Feb 12 21:00:13 volumio volumio[8145]: info: CoreStateMachine::getcurrentVolume Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 21:00:13 volumio sudo[8302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 21:00:13 volumio sudo[8302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:13 volumio sudo[8304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 21:00:13 volumio sudo[8304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:13 volumio sudo[8304]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:13 volumio sudo[8302]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:13 volumio volumio[8145]: info: Volumio Network Manager: Network status updated: 1 Feb 12 21:00:13 volumio volumio[8145]: info: VolumeController:: Volume=100 Mute =false Feb 12 21:00:13 volumio volumio[8145]: info: CoreStateMachine::pushState Feb 12 21:00:13 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::volumioPushState Feb 12 21:00:13 volumio volumio[8145]: info: CoreStateMachine::updateTrackBlock Feb 12 21:00:13 volumio volumio[8145]: info: CorePlayQueue::getTrackBlock Feb 12 21:00:13 volumio volumio[8145]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 21:00:13 volumio volumio-remote-updater[643]: [2026-02-12 21:00:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770904811 101 Feb 12 21:00:13 volumio volumio[8145]: 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 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:14 volumio volumio[8145]: info: Reloading queue from file Feb 12 21:00:14 volumio volumio[8145]: info: CoreStateMachine::setRepeat null single undefined Feb 12 21:00:14 volumio volumio[8145]: info: CoreStateMachine::pushState Feb 12 21:00:14 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::volumioPushState Feb 12 21:00:14 volumio volumio[8145]: info: CoreStateMachine::setRandom null Feb 12 21:00:14 volumio volumio[8145]: info: CoreStateMachine::pushState Feb 12 21:00:14 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::volumioPushState Feb 12 21:00:14 volumio volumio[8145]: info: Setting Device type: Raspberry PI Feb 12 21:00:14 volumio volumio[8145]: info: Completed loading Core Plugins Feb 12 21:00:14 volumio volumio[8145]: info: Preparing to generate the ALSA configuration file Feb 12 21:00:14 volumio volumio[8145]: info: VolumeController:: Volume=100 Mute =false Feb 12 21:00:14 volumio volumio[8145]: info: CoreStateMachine::pushState Feb 12 21:00:14 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::volumioPushState Feb 12 21:00:14 volumio volumio[8145]: info: Asound.conf file unchanged, so no further update is needed Feb 12 21:00:14 volumio volumio[8145]: info: Output device has changed, restarting MPD Feb 12 21:00:14 volumio sudo[8321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 21:00:14 volumio sudo[8321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:14 volumio volumio[8145]: info: Output device has changed, restarting Shairport Sync Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 12 21:00:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:14 volumio sudo[8324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 21:00:14 volumio sudo[8324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:14 volumio sudo[8324]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:14 volumio sudo[8326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 21:00:14 volumio sudo[8326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:14 volumio go-librespot[8329]: go-librespot daemon starting... Feb 12 21:00:14 volumio sudo[8321]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:14 volumio volumio[8145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 21:00:14 volumio volumio[8145]: info: ___________ START PLUGINS ___________ Feb 12 21:00:14 volumio go-librespot[8334]: time="2026-02-12T21:00:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:14 volumio go-librespot[8334]: time="2026-02-12T21:00:14+07:00" level=debug msg="app state loaded" Feb 12 21:00:14 volumio go-librespot[8334]: time="2026-02-12T21:00:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 21:00:14 volumio volumio[8145]: info: ControllerMpd::onStart: Initializing MPD Feb 12 21:00:14 volumio volumio[8145]: info: Creating MPD Configuration file Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 21:00:14 volumio volumio[8145]: info: [1770904814762] CoreMusicLibrary::Adding element Media Servers Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 21:00:14 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 21:00:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 21:00:14 volumio systemd[1]: mpd.service: Consumed 7.099s CPU time. Feb 12 21:00:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 21:00:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 21:00:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 21:00:14 volumio volumio[8145]: info: UPNP Browser: Client initialized successfully Feb 12 21:00:14 volumio sudo[8341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 21:00:14 volumio sudo[8341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:14 volumio sudo[8341]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:14 volumio sudo[8343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 21:00:14 volumio sudo[8343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:14 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 21:00:15 volumio nmbd[966]: [2026/02/12 21:00:15.049347, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 12 21:00:15 volumio volumio[8145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:15 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 12 21:00:15 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 12 21:00:15 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 21:00:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 21:00:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 21:00:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 21:00:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 21:00:15 volumio volumio[8145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 21:00:15 volumio volumio[8145]: info: [1770904815136] CoreMusicLibrary::Adding element Last_100 Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 21:00:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 21:00:15 volumio volumio[8145]: info: [1770904815156] CoreMusicLibrary::Adding element Webradio Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 21:00:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 21:00:15 volumio volumio[8145]: info: Initializing BBC Radios Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+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 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+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 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+07:00" level=info msg="zeroconf server listening on port 45219" Feb 12 21:00:15 volumio volumio[8145]: info: Creating Spotify config file Feb 12 21:00:15 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:15 volumio sudo[8360]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 21:00:15 volumio sudo[8360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 21:00:15 volumio sudo[8360]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+07:00" level=debug msg="obtained new client token: AADJ4wgYxLIAoiVQ6Gn0GPyqeZE1gepbO+ZeuEWbRQMwzLChT2BNOyxbdrzlWHX5kmpCyvg/kuJU/k5Gt6BLfjRCoOUSQbkjxqoakXMSKne8fwRylzPijes6fyuAX0LOBNZPdAsQ/z3uggXWymDrFNam5Yqd9T5EuTNYGmgBjvi/WB4tHsyyJdlPKiWdcJrLA+96i+i52iOYOR9MxwpOHznlxIV1gHtbdL5oEM7gN7YZxfoy/qypp+P8dw==" Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:15 volumio go-librespot[8334]: time="2026-02-12T21:00:15+07:00" level=debug msg="completed challenge" Feb 12 21:00:16 volumio go-librespot[8334]: time="2026-02-12T21:00:16+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 12 21:00:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:16 volumio volumio[8271]: Starting albumart workers Feb 12 21:00:16 volumio volumio[8145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 21:00:16 volumio volumio[8145]: info: [1770904816802] CoreMusicLibrary::Adding element YouTube Music Feb 12 21:00:16 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 21:00:16 volumio volumio[8145]: Cannot find translation for source YouTube Music Feb 12 21:00:16 volumio volumio[8145]: info: Volumio Calling Home Feb 12 21:00:16 volumio volumio[8270]: Starting albumart workers Feb 12 21:00:16 volumio volumio[8272]: Starting albumart workers Feb 12 21:00:16 volumio sudo[8377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 12 21:00:16 volumio sudo[8377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:17 volumio sudo[8377]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:17 volumio volumio[8145]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 21:00:17 volumio volumio[8145]: info: Discovery: Found device Volumio Feb 12 21:00:17 volumio volumio[8145]: info: CoreCommandRouter::volumioGetState Feb 12 21:00:17 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:17 volumio volumio[8145]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 21:00:17 volumio volumio[8145]: info: Discovery: Found device Volumio Feb 12 21:00:17 volumio volumio[8145]: info: CoreCommandRouter::volumioGetState Feb 12 21:00:17 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:17 volumio volumio[8145]: info: MPD Permissions set Feb 12 21:00:17 volumio volumio[8145]: info: MPD Permissions set Feb 12 21:00:17 volumio volumio[8145]: info: Upmpdcli Daemon Started Feb 12 21:00:17 volumio volumio[8145]: info: Volumio called home Feb 12 21:00:17 volumio volumio[8145]: info: Spotify config file written Feb 12 21:00:18 volumio sudo[8383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 21:00:18 volumio sudo[8383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:18 volumio volumio[8145]: 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 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio go-librespot[8390]: go-librespot daemon starting... Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio sudo[8383]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:00:18 volumio volumio[8145]: info: No need to fix Spotify hosts Feb 12 21:00:18 volumio go-librespot[8391]: time="2026-02-12T21:00:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:18 volumio go-librespot[8391]: time="2026-02-12T21:00:18+07:00" level=debug msg="app state loaded" Feb 12 21:00:18 volumio go-librespot[8391]: time="2026-02-12T21:00:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+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 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+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 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+07:00" level=info msg="zeroconf server listening on port 37651" Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+07:00" level=debug msg="obtained new client token: AAA3v9UQJJKMnCdbYVq5RL7FxbGZDXb+Z9UgFAcNTK+HekXctMmdvDMgQY7veNUp0fF4hls4bxsUuaI62kSNHJBXAE6Qj8hCoY/YabB7vaU19yTgMNIHWhn5CfexaaXi6LJm8vvp4EVDHezMHxUqA/nhLwX+woCnBKKrR2CZV07+lcIoswHh2UGhuzbv3LAnF1wtAj5d9j/gvB8LNJtfo1K3kjHDlnEiXjNuorUcDIJx37vpzQ22I+zHTQ==" Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 21:00:19 volumio volumio[8145]: info: Starting Shairport Sync Feb 12 21:00:19 volumio volumio[8145]: info: Starting Shairport Sync Feb 12 21:00:19 volumio volumio[8145]: info: Starting Shairport Sync Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00:19+07:00" level=debug msg="completed challenge" Feb 12 21:00:19 volumio sudo[8422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 21:00:19 volumio sudo[8422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:19 volumio sudo[8424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 21:00:19 volumio sudo[8424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:19 volumio sudo[8426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 21:00:19 volumio sudo[8426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:19 volumio go-librespot[8391]: time="2026-02-12T21:00: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 12 21:00:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 21:00:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 21:00:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 21:00:19 volumio systemd[1]: shairport-sync.service: Consumed 2.283s CPU time. Feb 12 21:00:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 21:00:19 volumio volumio[8145]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 21:00:19 volumio volumio[8145]: SPOTIFY: BQCkiyVtIu4kS49-a6o5UOmDGTrkZNzOtW8ztQE5WiEVTuOVk7HhJKiSYT8_EJKny8t9u2WlPdBcn9PU0D4SQXpQCS4Q5hTNWru3dByDj2a6aMSw3yAWj0rWnNBJ8oRsAu0HwDGLe3FiVIdwmeiWC11gbqJWNeIaZz_NkNlCEzZqxlrQ6-yyMZmqZiYTvsi3kC1UNlCbWtBYMLDNMl1eM6H77ELZl8GfIiFUZjebYUinO8GcOVDQghsmYuHgZ06zXPAMe0pE7yN5sI417CD221bYr4OOJyaHwKoVsTTwtmj4v3v4XBmNLKHo Feb 12 21:00:19 volumio volumio[8145]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 21:00:19 volumio volumio[8145]: info: New Spotify access token = BQCkiyVtIu4kS49-a6o5UOmDGTrkZNzOtW8ztQE5WiEVTuOVk7HhJKiSYT8_EJKny8t9u2WlPdBcn9PU0D4SQXpQCS4Q5hTNWru3dByDj2a6aMSw3yAWj0rWnNBJ8oRsAu0HwDGLe3FiVIdwmeiWC11gbqJWNeIaZz_NkNlCEzZqxlrQ6-yyMZmqZiYTvsi3kC1UNlCbWtBYMLDNMl1eM6H77ELZl8GfIiFUZjebYUinO8GcOVDQghsmYuHgZ06zXPAMe0pE7yN5sI417CD221bYr4OOJyaHwKoVsTTwtmj4v3v4XBmNLKHo Feb 12 21:00:19 volumio volumio[8145]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 21:00:19 volumio sudo[8422]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:20 volumio sudo[8424]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:20 volumio sudo[8426]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:20 volumio volumio[8145]: info: Shairport-Sync Started Feb 12 21:00:20 volumio volumio[8145]: Error adding Membership: Error: addMembership EINVAL Feb 12 21:00:20 volumio volumio[8145]: info: Shairport-Sync Started Feb 12 21:00:20 volumio volumio[8145]: info: Shairport-Sync Started Feb 12 21:00:20 volumio volumio[8145]: info: CoreCommandRouter::volumioGetState Feb 12 21:00:20 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:20 volumio volumio[8145]: 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 12 21:00:20 volumio volumio[8145]: info: Spotify Successfully logged in Feb 12 21:00:20 volumio volumio[8145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 21:00:20 volumio volumio[8145]: info: [1770904820545] CoreMusicLibrary::Adding element Spotify Feb 12 21:00:20 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 21:00:20 volumio volumio[8145]: Cannot find translation for source YouTube Music Feb 12 21:00:20 volumio volumio[8145]: Cannot find translation for source Spotify Feb 12 21:00:20 volumio volumio[8145]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 12 21:00:20 volumio volumio[8145]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 21:00:21 volumio volumio[8145]: info: VolumeController:: Volume=100 Mute =false Feb 12 21:00:21 volumio volumio[8145]: info: CoreCommandRouter::volumioGetState Feb 12 21:00:21 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:21 volumio volumio[8145]: info: CoreStateMachine::pushState Feb 12 21:00:21 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:21 volumio volumio[8145]: info: CoreCommandRouter::volumioPushState Feb 12 21:00:22 volumio volumio[8145]: info: go-librespot daemon successfully initialized Feb 12 21:00:22 volumio mpd[8375]: 2026-02-12T21:00:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 21:00:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 21:00:22 volumio sudo[8326]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:22 volumio sudo[8343]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 12 21:00:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:22 volumio go-librespot[8466]: go-librespot daemon starting... Feb 12 21:00:22 volumio go-librespot[8467]: time="2026-02-12T21:00:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:22 volumio go-librespot[8467]: time="2026-02-12T21:00:22+07:00" level=debug msg="app state loaded" Feb 12 21:00:22 volumio go-librespot[8467]: time="2026-02-12T21:00:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:22 volumio volumio[8145]: error: MPD error: The expression evaluated to a falsy value: Feb 12 21:00:22 volumio volumio[8145]: assert.ok(self.idling) Feb 12 21:00:22 volumio volumio[8145]: error: The expression evaluated to a falsy value: Feb 12 21:00:22 volumio volumio[8145]: assert.ok(self.idling) Feb 12 21:00:22 volumio volumio[8145]: error: updateQueue error: null Feb 12 21:00:23 volumio volumio[8145]: info: MPD running with PID8375 Feb 12 21:00:23 volumio volumio[8145]: ,establishing connection Feb 12 21:00:23 volumio volumio[8145]: info: Completed starting Core Plugins Feb 12 21:00:23 volumio volumio[8145]: info: ------------------------------------------- Feb 12 21:00:23 volumio volumio[8145]: info: ----- MyVolumio plugins startup ---- Feb 12 21:00:23 volumio volumio[8145]: info: ------------------------------------------- Feb 12 21:00:23 volumio volumio[8145]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 21:00:23 volumio volumio[8145]: error: updateQueue error: null Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=info msg="zeroconf server listening on port 40807" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="obtained new client token: AADbJx2TX9tmVtB97A0eRwrlwsKFQ6oW8b13jHXOatw/OV5FdcGcPyFE/fQ2rXX74Ro/PTSMMlwGo9PIqe9+p0Kh7EmFFfJN7oMK5S42HO4qQtf5xruLE+jtHRV1Ea26UnVgRHegJyMz1P0LIQNl7ab/ic2NYLT4oWGpLKcmKNtLND2t+AU6WCqAOd08u5F5vBuacgsB8gb/fZcc+IvN6n2xG/TCCfLzDNyuYzJhd1phcrxjg7wCiV3YjQ==" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+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 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00:23+07:00" level=debug msg="completed challenge" Feb 12 21:00:23 volumio go-librespot[8467]: time="2026-02-12T21:00: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 12 21:00:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:25 volumio volumio[8145]: info: Initializing connection to go-librespot Websocket Feb 12 21:00:25 volumio volumio[8145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 21:00:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 12 21:00:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:27 volumio go-librespot[8474]: go-librespot daemon starting... Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+07:00" level=debug msg="app state loaded" Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+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 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+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 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+07:00" level=info msg="zeroconf server listening on port 44493" Feb 12 21:00:27 volumio volumio[8145]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+07:00" level=debug msg="obtained new client token: AADlLd9QVzk/1SzCs69/+567Y0wkaTrJ3CMJ9KLs0X/V+zNDT26ltfxNxNgYeNOFvKSNQKHTkWkHqQU36RxALg3V/O6AmAbghI66xsIroOeCMVDDJ9g9GB5HHe/Ew4a+Gkwq+6Nxedkt62EKQbdPK7s0VPOcMerGvAB47e8mZWqruP3hro66sv5SyTVnSOAPpbmC9pilemoOTnpf8G0+zKEeMuvZ21WYkwOi6WFGQGtZRoMcwnDVCfpM7g==" Feb 12 21:00:27 volumio go-librespot[8475]: time="2026-02-12T21:00:27+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 12 21:00:28 volumio go-librespot[8475]: time="2026-02-12T21:00:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 12 21:00:28 volumio go-librespot[8475]: time="2026-02-12T21:00:28+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:28 volumio go-librespot[8475]: time="2026-02-12T21:00:28+07:00" level=debug msg="completed challenge" Feb 12 21:00:28 volumio go-librespot[8475]: time="2026-02-12T21:00:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 21:00:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:28 volumio volumio[8145]: info: Initializing connection to go-librespot Websocket Feb 12 21:00:28 volumio volumio[8145]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 21:00:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 12 21:00:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:31 volumio go-librespot[8482]: go-librespot daemon starting... Feb 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00:31+07:00" level=debug msg="app state loaded" Feb 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:31 volumio volumio[8145]: info: Initializing connection to go-librespot Websocket Feb 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00:31+07:00" level=debug msg="new websocket client" Feb 12 21:00:31 volumio volumio[8145]: info: Connection to go-librespot Websocket established Feb 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00: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 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00: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 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00: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 12 21:00:31 volumio go-librespot[8483]: time="2026-02-12T21:00:31+07:00" level=info msg="zeroconf server listening on port 44037" Feb 12 21:00:32 volumio go-librespot[8483]: time="2026-02-12T21:00:32+07:00" level=debug msg="obtained new client token: AACgy23R3zeahrGmWsj/QtV9GCuSrfp/aTg3A2fuYn717qwSbbaY8hWRMGouUAvJqa6/WfeLMk63SAsZYdhoD6lY+85/hM57deykxZLBIz889QPmNoZzfHcLqvHvo49DErKKKzB813SqDRCQa19su0BTWAhKhxQhCu3CIcIjXjbjfVs8iuC7VI8U6sE71fBpOw2a2ev1aBa+JEsxOpglW+SMTz9UfZs0IsmtfBy9JZ00JsQaCZgNvcI=" Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin bluetooth to MyMusic Plugins Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin multiroom to MyMusic Plugins Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin metavolumio to MyMusic Plugins Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin cd_controller to MyMusic Plugins Feb 12 21:00:32 volumio go-librespot[8483]: time="2026-02-12T21:00:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 12 21:00:32 volumio volumio[8145]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 12 21:00:32 volumio volumio[8145]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 12 21:00:32 volumio go-librespot[8483]: time="2026-02-12T21:00:32+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:32 volumio go-librespot[8483]: time="2026-02-12T21:00:32+07:00" level=debug msg="completed challenge" Feb 12 21:00:32 volumio go-librespot[8483]: time="2026-02-12T21:00: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 12 21:00:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:34 volumio volumio[8145]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 21:00:34 volumio volumio[8145]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 21:00:34 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:34 volumio volumio[8145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:00:34 volumio volumio[8145]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 21:00:34 volumio volumio[8145]: info: MyVolumio login type: Token Feb 12 21:00:34 volumio volumio[8145]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 21:00:34 volumio volumio[8145]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 21:00:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 12 21:00:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 21:00:35 volumio go-librespot[8505]: go-librespot daemon starting... Feb 12 21:00:35 volumio go-librespot[8506]: time="2026-02-12T21:00:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 21:00:35 volumio go-librespot[8506]: time="2026-02-12T21:00:35+07:00" level=debug msg="app state loaded" Feb 12 21:00:35 volumio go-librespot[8506]: time="2026-02-12T21:00:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 21:00:35 volumio volumio[8145]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 21:00:35 volumio volumio[8145]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 21:00:35 volumio volumio[8145]: info: Streaming services startup Feb 12 21:00:35 volumio volumio[8145]: info: Starting Streaming Daemon Feb 12 21:00:35 volumio sudo[8513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 21:00:35 volumio volumio[8145]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 21:00:35 volumio sudo[8513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:00:36 volumio sudo[8513]: pam_unix(sudo:session): session closed for user root Feb 12 21:00:36 volumio volumio[8145]: info: Getting Spotify volume Feb 12 21:00:36 volumio volumio[8145]: info: Connection to go-librespot Websocket closed Feb 12 21:00:36 volumio volumio[8145]: error: Cannot start Volumio Streaming Daemon Feb 12 21:00:36 volumio volumio[8145]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 21:00:36 volumio volumio[8145]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 21:00:36 volumio volumio[8145]: 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 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+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 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+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 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+07:00" level=info msg="zeroconf server listening on port 37565" Feb 12 21:00:36 volumio volumio[8145]: info: CoreCommandRouter::volumioGetState Feb 12 21:00:36 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:36 volumio volumio[8145]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 12 21:00:36 volumio volumio[8145]: SPOTIFY: SPOTIFY VOLUME undefined Feb 12 21:00:36 volumio volumio[8145]: SPOTIFY: VOLUMIO VOLUME 100 Feb 12 21:00:36 volumio volumio[8145]: info: Aligning Spotify Volume to Volumio Volume Feb 12 21:00:36 volumio volumio[8145]: info: CoreCommandRouter::volumioGetState Feb 12 21:00:36 volumio volumio[8145]: info: CorePlayQueue::getTrack 0 Feb 12 21:00:36 volumio volumio[8145]: info: Setting Spotify Volume from Volumio: 100 Feb 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+07:00" level=debug msg="obtained new client token: AAATu7iGpoGTLnXG4Ob7Agv8VRohTVoZIV4bXAqd9M9D5MsTiv7LvPekIH/QpmT5mWJiY6hTXmQqqG0MZUX9WXRKwCPYG1wiesXDfJyn0Z5G2lG5IbAXID29Dx+eN4Mw5tmBI+0eFvgWREz6pAIu8bUQE36IF0G7u/QQ2Vvb/fxtAuiMb3cyrHsgs6qXI51rs80tAJwVns8JsmSg6WVZH9JAZkk4jjt2ni72dDh/c9dAddb+FVsHsZSygg==" Feb 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+07:00" level=debug msg="completed keyexchange" Feb 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+07:00" level=debug msg="completed challenge" Feb 12 21:00:36 volumio go-librespot[8506]: time="2026-02-12T21:00:36+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 12 21:00:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:00:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 21:00:36 volumio volumio[8145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 21:00:37 volumio volumio[8145]: Error: socket hang up Feb 12 21:00:37 volumio volumio[8145]: at connResetException (node:internal/errors:720:14) Feb 12 21:00:37 volumio volumio[8145]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 12 21:00:37 volumio volumio[8145]: at Socket.emit (node:events:526:35) Feb 12 21:00:37 volumio volumio[8145]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 12 21:00:37 volumio volumio[8145]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 12 21:00:37 volumio volumio[8145]: code: 'ECONNRESET', Feb 12 21:00:37 volumio volumio[8145]: response: undefined Feb 12 21:00:37 volumio volumio[8145]: } Feb 12 21:00:37 volumio volumio[8145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 21:00:38 volumio sudo[8535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 20:59' Feb 12 21:00:38 volumio sudo[8535]: 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"