Feb 13 10:52:00 volumio sudo[4040]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 10:52:00 volumio sudo[4040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 10:52:00 volumio sudo[4040]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:00 volumio volumio[3828]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:00 volumio volumio[3828]: info: [1770954720834] CoreMusicLibrary::Adding element YouTube Music Feb 13 10:52:00 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:00 volumio volumio[3828]: Cannot find translation for source YouTube Music Feb 13 10:52:00 volumio volumio[3828]: info: Volumio Calling Home Feb 13 10:52:01 volumio sudo[4057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 10:52:01 volumio sudo[4057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:01 volumio sudo[4057]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 13 10:52:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:01 volumio go-librespot[4064]: go-librespot daemon starting... Feb 13 10:52:01 volumio volumio[3962]: Starting albumart workers Feb 13 10:52:01 volumio go-librespot[4066]: time="2026-02-13T10:52:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:01 volumio volumio[3963]: Starting albumart workers Feb 13 10:52:01 volumio volumio[3961]: Starting albumart workers Feb 13 10:52:01 volumio go-librespot[4066]: time="2026-02-13T10:52:01+07:00" level=info msg="zeroconf server listening on port 39747" Feb 13 10:52:01 volumio volumio[3828]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:52:01 volumio volumio[3828]: info: Discovery: Found device Volumio Feb 13 10:52:01 volumio volumio[3828]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:01 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:01 volumio volumio[3828]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:52:01 volumio volumio[3828]: info: Discovery: Found device Volumio Feb 13 10:52:01 volumio volumio[3828]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:01 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:02 volumio volumio[3828]: info: MPD Permissions set Feb 13 10:52:02 volumio volumio[3828]: info: MPD Permissions set Feb 13 10:52:02 volumio volumio[3828]: info: Upmpdcli Daemon Started Feb 13 10:52:02 volumio volumio[3828]: info: Volumio called home Feb 13 10:52:02 volumio volumio[3828]: info: Spotify config file written Feb 13 10:52:02 volumio sudo[4075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 10:52:02 volumio sudo[4075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:02 volumio volumio[3828]: 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 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 10:52:02 volumio systemd[1]: go-librespot-daemon.service: Killing process 4071 (go-librespot) with signal SIGKILL. Feb 13 10:52:02 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 10:52:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:02 volumio go-librespot[4080]: go-librespot daemon starting... Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio sudo[4075]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio go-librespot[4085]: time="2026-02-13T10:52:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:02 volumio go-librespot[4085]: time="2026-02-13T10:52:02+07:00" level=debug msg="app state loaded" Feb 13 10:52:02 volumio go-librespot[4085]: time="2026-02-13T10:52:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:02 volumio volumio[3828]: info: No need to fix Spotify hosts Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=info msg="zeroconf server listening on port 33041" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="obtained new client token: AACAn8QcmxM3oub5zgYxSDi7MFmBpLQSbK1Q8GKSDRe2OtH4p5AICwqgMO0d7T5FHnhgMPFdccUe9Oe0ygNc56D4Xwy4wbN+FQzA3NS8c5MdWR5ZZ5afKsFa3jiuxpKsH1QHx0nL8ImkseNX4OwCKaAf6p2iniPzTuFDXb6QLGlRfFDY/awV3YeDZQhi4Xhl3hsgaRPfqwYq1lim5AHMW5uA8emV2utNzHhmYve73NzhyFOQcUqg3F/2Mg==" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=debug msg="completed challenge" Feb 13 10:52:03 volumio go-librespot[4085]: time="2026-02-13T10:52:03+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:04 volumio volumio[3828]: info: Starting Shairport Sync Feb 13 10:52:04 volumio volumio[3828]: info: Starting Shairport Sync Feb 13 10:52:04 volumio volumio[3828]: info: Starting Shairport Sync Feb 13 10:52:04 volumio sudo[4116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:52:04 volumio sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:04 volumio sudo[4118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:52:04 volumio sudo[4118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:04 volumio sudo[4120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:52:04 volumio sudo[4120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 10:52:04 volumio volumio[3828]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:52:04 volumio volumio[3828]: SPOTIFY: BQAHoMh26CRsVCSznQVRMTVfcK5kMX7fsscIQEU-Cv5pCETgWcK3_NHlvKD5rJBCuNSeEnIrz5LpXWQnjVzL38cgOa1pdUGzqeIBgmD6q0LRvpDrVL_dB8vVt9w81e9ZnGiG4n2p1wrF-eO3zPLqq-XFIDdTFFnOL8HyDrwMgcGQFXyVXYK0D0OpLDuDbC5HzaY3q_GPQ7ERpSS43ouXMbMiuHhVveWBzWMjADelBn8aplqkSAMOO6JZbHtmEsxtGdXdIhPCL5W0rZo83JaEbh4PpUxMbh0ChuPpXXPqbiunruAvEo8zTGpD Feb 13 10:52:04 volumio volumio[3828]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:52:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 10:52:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:52:04 volumio systemd[1]: shairport-sync.service: Consumed 6.214s CPU time. Feb 13 10:52:04 volumio volumio[3828]: info: New Spotify access token = BQAHoMh26CRsVCSznQVRMTVfcK5kMX7fsscIQEU-Cv5pCETgWcK3_NHlvKD5rJBCuNSeEnIrz5LpXWQnjVzL38cgOa1pdUGzqeIBgmD6q0LRvpDrVL_dB8vVt9w81e9ZnGiG4n2p1wrF-eO3zPLqq-XFIDdTFFnOL8HyDrwMgcGQFXyVXYK0D0OpLDuDbC5HzaY3q_GPQ7ERpSS43ouXMbMiuHhVveWBzWMjADelBn8aplqkSAMOO6JZbHtmEsxtGdXdIhPCL5W0rZo83JaEbh4PpUxMbh0ChuPpXXPqbiunruAvEo8zTGpD Feb 13 10:52:04 volumio volumio[3828]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 10:52:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:52:04 volumio sudo[4116]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:04 volumio sudo[4118]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:04 volumio sudo[4120]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:04 volumio volumio[3828]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:04 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:04 volumio volumio[3828]: info: Shairport-Sync Started Feb 13 10:52:04 volumio volumio[3828]: Error adding Membership: Error: addMembership EINVAL Feb 13 10:52:04 volumio volumio[3828]: info: Shairport-Sync Started Feb 13 10:52:04 volumio volumio[3828]: info: Shairport-Sync Started Feb 13 10:52:04 volumio volumio[3828]: 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 13 10:52:04 volumio volumio[3828]: info: Spotify Successfully logged in Feb 13 10:52:04 volumio volumio[3828]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:04 volumio volumio[3828]: info: [1770954724956] CoreMusicLibrary::Adding element Spotify Feb 13 10:52:04 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:04 volumio volumio[3828]: Cannot find translation for source YouTube Music Feb 13 10:52:04 volumio volumio[3828]: Cannot find translation for source Spotify Feb 13 10:52:05 volumio volumio[3828]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 10:52:05 volumio volumio[3828]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:52:05 volumio volumio[3828]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:52:05 volumio volumio[3828]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:05 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:05 volumio volumio[3828]: info: CoreStateMachine::pushState Feb 13 10:52:05 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:05 volumio volumio[3828]: info: CoreCommandRouter::volumioPushState Feb 13 10:52:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 10:52:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:07 volumio volumio[3828]: info: go-librespot daemon successfully initialized Feb 13 10:52:07 volumio mpd[4055]: 2026-02-13T10:52:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 10:52:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:07 volumio go-librespot[4156]: go-librespot daemon starting... Feb 13 10:52:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 10:52:07 volumio sudo[4026]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:07 volumio sudo[4016]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+07:00" level=debug msg="app state loaded" Feb 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:07 volumio volumio[3828]: error: MPD error: The expression evaluated to a falsy value: Feb 13 10:52:07 volumio volumio[3828]: assert.ok(self.idling) Feb 13 10:52:07 volumio volumio[3828]: error: The expression evaluated to a falsy value: Feb 13 10:52:07 volumio volumio[3828]: assert.ok(self.idling) Feb 13 10:52:07 volumio volumio[3828]: info: Completed starting Core Plugins Feb 13 10:52:07 volumio volumio[3828]: info: ------------------------------------------- Feb 13 10:52:07 volumio volumio[3828]: info: ----- MyVolumio plugins startup ---- Feb 13 10:52:07 volumio volumio[3828]: info: ------------------------------------------- Feb 13 10:52:07 volumio volumio[3828]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 10:52:07 volumio volumio[3828]: info: MPD running with PID4055 Feb 13 10:52:07 volumio volumio[3828]: ,establishing connection Feb 13 10:52:07 volumio volumio[3828]: error: updateQueue error: null Feb 13 10:52:07 volumio volumio[3828]: error: updateQueue error: null Feb 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+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 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+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 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+07:00" level=info msg="zeroconf server listening on port 45341" Feb 13 10:52:07 volumio go-librespot[4159]: time="2026-02-13T10:52:07+07:00" level=debug msg="obtained new client token: AAAC/eKrTiFs/uwYPKiJkojDjkqtOB5WyudLLDRLIZRenSS+oylX0QC2gbk5cKvevZdJoYLaJLQRMHO1pYq3a3T7cTmmG9QVG6XX7naPEGnab+88YeU1PA7NKoE2ohwh6TDFnO813Z7Xy/Jf8Xa2Z0v3yB7O67F1lfHrb6olniKA3Xcov1U4puz7sviqd2xD2kn1W2HaWJ/4WntDTj02/DIxGsuYyehXya/xj7vEKQ8OzsWwg40BkKaAhg==" Feb 13 10:52:08 volumio go-librespot[4159]: time="2026-02-13T10:52:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:08 volumio go-librespot[4159]: time="2026-02-13T10:52:08+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:08 volumio go-librespot[4159]: time="2026-02-13T10:52:08+07:00" level=debug msg="completed challenge" Feb 13 10:52:08 volumio go-librespot[4159]: time="2026-02-13T10:52:08+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:10 volumio volumio[3828]: info: Initializing connection to go-librespot Websocket Feb 13 10:52:10 volumio volumio[3828]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:52:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 10:52:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:11 volumio go-librespot[4169]: go-librespot daemon starting... Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=debug msg="app state loaded" Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:52:11 volumio go-librespot[4170]: time="2026-02-13T10:52:11+07:00" level=info msg="zeroconf server listening on port 46639" Feb 13 10:52:12 volumio go-librespot[4170]: time="2026-02-13T10:52:12+07:00" level=debug msg="obtained new client token: AABRpn1qks49lfz9O63/D/OyfQIGnJR7cW6jvb7WsyiLpRSb5aXR0uGnQalX9xWrwwkQ0oZ2PpvYup6Xx8CytWOvb9raOoHTWv4W1iiN65L/eDNouYs9xLAgkC8e+56v0P1+uGd/SPLxDpon8JyvubVGaGclHWMGh/orb22gyWI73MHow9Sn/j9tnX5cr+0kb7phcfugk/Fg7pVqy0PJ9kUn25GRzLwvNFAyowgai/0DtK+MDbQE3rE=" Feb 13 10:52:12 volumio go-librespot[4170]: time="2026-02-13T10:52:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:12 volumio volumio[3828]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 10:52:12 volumio go-librespot[4170]: time="2026-02-13T10:52:12+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:12 volumio go-librespot[4170]: time="2026-02-13T10:52:12+07:00" level=debug msg="completed challenge" Feb 13 10:52:12 volumio go-librespot[4170]: time="2026-02-13T10:52:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:13 volumio volumio[3828]: info: Initializing connection to go-librespot Websocket Feb 13 10:52:13 volumio volumio[3828]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:52:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 10:52:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:15 volumio go-librespot[4177]: go-librespot daemon starting... Feb 13 10:52:15 volumio go-librespot[4178]: time="2026-02-13T10:52:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:15 volumio go-librespot[4178]: time="2026-02-13T10:52:15+07:00" level=debug msg="app state loaded" Feb 13 10:52:15 volumio go-librespot[4178]: time="2026-02-13T10:52:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:16 volumio volumio[3828]: info: Initializing connection to go-librespot Websocket Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="new websocket client" Feb 13 10:52:16 volumio volumio[3828]: info: Connection to go-librespot Websocket established Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=info msg="zeroconf server listening on port 42733" Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 10:52:16 volumio volumio[3828]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="obtained new client token: AABtV/VppHUl7Ebu83dBB2ZtZnb9sv0KMBicqAFWOEVLaA/LcKzy1Bl17nSTHh6T2Hc/3ACNuEUKSKCoQagsST9pr76giRXtMX34vfWvcftDxf8tfVY0vVlPZ0PTnX3RGLodrd6EwQanOZBw5RF8Gkxr/o7vaLXaDz08G9dX7UFtq21vnaDmlly6kMKbYf6kgU+Kt1HiT2teEfR69kcwP681L7UizR9CbqTpHbzVNlAuSG4XdqI1NGSeIg==" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52:16+07:00" level=debug msg="completed challenge" Feb 13 10:52:16 volumio go-librespot[4178]: time="2026-02-13T10:52: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 13 10:52:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:18 volumio volumio[3828]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 10:52:18 volumio volumio[3828]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 10:52:18 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:18 volumio volumio[3828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:18 volumio volumio[3828]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 10:52:18 volumio volumio[3828]: info: MyVolumio login type: Token Feb 13 10:52:18 volumio volumio[3828]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 10:52:18 volumio volumio[3828]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 10:52:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 10:52:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:19 volumio go-librespot[4200]: go-librespot daemon starting... Feb 13 10:52:19 volumio go-librespot[4201]: time="2026-02-13T10:52:19+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:19 volumio go-librespot[4201]: time="2026-02-13T10:52:19+07:00" level=debug msg="app state loaded" Feb 13 10:52:19 volumio go-librespot[4201]: time="2026-02-13T10:52:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:20 volumio volumio[3828]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 10:52:20 volumio volumio[3828]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 10:52:20 volumio volumio[3828]: info: Streaming services startup Feb 13 10:52:20 volumio volumio[3828]: info: Starting Streaming Daemon Feb 13 10:52:20 volumio sudo[4208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:52:20 volumio sudo[4208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:20 volumio volumio[3828]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 10:52:20 volumio sudo[4208]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:20 volumio volumio[3828]: info: Getting Spotify volume Feb 13 10:52:20 volumio volumio[3828]: info: Connection to go-librespot Websocket closed Feb 13 10:52:20 volumio volumio[3828]: error: Cannot start Volumio Streaming Daemon Feb 13 10:52:20 volumio volumio[3828]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 10:52:20 volumio volumio[3828]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 10:52:20 volumio volumio[3828]: 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 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+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 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+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 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+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 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+07:00" level=info msg="zeroconf server listening on port 41471" Feb 13 10:52:20 volumio volumio[3828]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:20 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:20 volumio volumio[3828]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 13 10:52:20 volumio volumio[3828]: SPOTIFY: SPOTIFY VOLUME undefined Feb 13 10:52:20 volumio volumio[3828]: SPOTIFY: VOLUMIO VOLUME 100 Feb 13 10:52:20 volumio volumio[3828]: info: Aligning Spotify Volume to Volumio Volume Feb 13 10:52:20 volumio volumio[3828]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:20 volumio volumio[3828]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:20 volumio volumio[3828]: info: Setting Spotify Volume from Volumio: 100 Feb 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+07:00" level=debug msg="obtained new client token: AACKHIa2McycOZbtqWmOO5CKyYpk5zdDjL3CmqqJcX35BBojASRqb+OlGOIefTRRZCgFz5HvNrulah9psb4yJyvfLm8RzbzE8JxdMc+KF1kamn7Q1uQCeo3C9qSSnF6yNxYBXgApy9F14DwFl0jnxu2yKh3cQ3eee5fGCbatyVzSORsISpqiJg604UyO3D6kVRWgswl2wL+W5oKGnbEKpqMzwgUTOGdhYaAmLLeVshZKNpIxnBmD5xH+4g==" Feb 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+07:00" level=debug msg="completed challenge" Feb 13 10:52:20 volumio go-librespot[4201]: time="2026-02-13T10:52:20+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:20 volumio volumio[3828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:52:20 volumio volumio[3828]: Error: socket hang up Feb 13 10:52:20 volumio volumio[3828]: at connResetException (node:internal/errors:720:14) Feb 13 10:52:20 volumio volumio[3828]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 13 10:52:20 volumio volumio[3828]: at Socket.emit (node:events:526:35) Feb 13 10:52:20 volumio volumio[3828]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 13 10:52:20 volumio volumio[3828]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 13 10:52:20 volumio volumio[3828]: code: 'ECONNRESET', Feb 13 10:52:20 volumio volumio[3828]: response: undefined Feb 13 10:52:20 volumio volumio[3828]: } Feb 13 10:52:20 volumio volumio[3828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:52:22 volumio sudo[4230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 10:51' Feb 13 10:52:22 volumio sudo[4230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:22 volumio sudo[4230]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:22 volumio volumio-remote-updater[643]: [2026-02-13 10:52:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 13 10:52:22 volumio volumio-remote-updater[643]: [2026-02-13 10:52:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 13 10:52:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 10:52:22 volumio systemd[1]: volumio.service: Consumed 55.598s CPU time. Feb 13 10:52:22 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:52:22 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:52:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14593. Feb 13 10:52:22 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:52:22 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 10:52:22 volumio systemd[1]: volumio.service: Consumed 55.598s CPU time. Feb 13 10:52:22 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 10:52:22 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:52:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 10:52:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:24 volumio go-librespot[4257]: go-librespot daemon starting... Feb 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+07:00" level=debug msg="app state loaded" Feb 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+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 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+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 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+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 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+07:00" level=info msg="zeroconf server listening on port 42251" Feb 13 10:52:24 volumio go-librespot[4258]: time="2026-02-13T10:52:24+07:00" level=debug msg="obtained new client token: AAC2URrkadgejmCf/Mm8NAc6P9FzrU6LESBNqZQ9l0AedbPxlp6WXIU4w3KH3M6R5CZRvnmGN2cN0H6Zjxb4y4Fi51nmjgJwGFkzBz1mJ0zMPWz1nput1MKRR7nbdcvzSomiUwIWZT7gB6TRx6iAfq7073L+F9XN8S4rnDVIrP1tHAZfFpYafGWaQB4+maq7Xd5gRw8iIwWIC8MF365NqeYG2mxV+NOVl4f+2AdYluwFtEleSkvte0cmkg==" Feb 13 10:52:25 volumio go-librespot[4258]: time="2026-02-13T10:52:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:25 volumio go-librespot[4258]: time="2026-02-13T10:52:25+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:25 volumio go-librespot[4258]: time="2026-02-13T10:52:25+07:00" level=debug msg="completed challenge" Feb 13 10:52:25 volumio go-librespot[4258]: time="2026-02-13T10:52:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:26 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:26 volumio volumio[4242]: info: ----- Volumio3 ---- Feb 13 10:52:26 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:26 volumio volumio[4242]: info: ----- System startup ---- Feb 13 10:52:26 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:27 volumio volumio-remote-updater[643]: [2026-02-13 10:52:27] [connect] Successful connection Feb 13 10:52:27 volumio volumio[4242]: info: MYVOLUMIO Environment detected Feb 13 10:52:28 volumio volumio[4242]: info: Plugin folders cleanup Feb 13 10:52:28 volumio volumio[4242]: info: Scanning into folder /volumio/app/plugins/ Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category audio_interface Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category miscellanea Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category music_service Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category plugins.json Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category system_controller Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category user_interface Feb 13 10:52:28 volumio volumio[4242]: info: Scanning into folder /data/plugins/ Feb 13 10:52:28 volumio volumio[4242]: info: Scanning category music_service Feb 13 10:52:28 volumio volumio[4242]: info: Plugin folders cleanup completed Feb 13 10:52:28 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:28 volumio volumio[4242]: info: ----- Core plugins startup ---- Feb 13 10:52:28 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:28 volumio volumio[4242]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 10:52:28 volumio volumio[4242]: info: Adding plugin upnp to MyMusic Plugins Feb 13 10:52:28 volumio volumio[4242]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 10:52:28 volumio volumio[4242]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 10:52:28 volumio volumio[4242]: info: Loading plugins from folder /data/plugins/ Feb 13 10:52:28 volumio volumio[4242]: info: Loading plugin "system"... Feb 13 10:52:28 volumio volumio[4242]: info: Loading plugin "appearance"... Feb 13 10:52:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 10:52:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:28 volumio go-librespot[4278]: go-librespot daemon starting... Feb 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+07:00" level=debug msg="app state loaded" Feb 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+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 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+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 13 10:52:28 volumio go-librespot[4279]: time="2026-02-13T10:52:28+07:00" level=info msg="zeroconf server listening on port 33971" Feb 13 10:52:29 volumio go-librespot[4279]: time="2026-02-13T10:52:29+07:00" level=debug msg="obtained new client token: AAAF6XIDhwOW7Ykl/jrzIuEyF98uCg7xJsJpyxLzrSqNSfIrpojQDOxFqPly/FkAIloyOJze31g347nidouk/XcDPP4A1XfRVk8HJCwOxkb8mf2eigpNxn/voahhfmnrJxjOBRGquoJO6Sg9qhto3wBx8r0/vqwcn7AW8dyaBKeV0UThVKvQUDFYYQ5TH75XpjuxSwPewW5+cUlg6sefpqqKVaJH7o/25xrKlEHu1saappbZ167FK2I=" Feb 13 10:52:29 volumio go-librespot[4279]: time="2026-02-13T10:52:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:29 volumio go-librespot[4279]: time="2026-02-13T10:52:29+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:29 volumio go-librespot[4279]: time="2026-02-13T10:52:29+07:00" level=debug msg="completed challenge" Feb 13 10:52:29 volumio go-librespot[4279]: time="2026-02-13T10:52:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "network"... Feb 13 10:52:30 volumio volumio[4242]: info: Refreshing Cached IP Addresses Feb 13 10:52:30 volumio sudo[4288]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:52:30 volumio sudo[4288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:30 volumio sudo[4290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "services"... Feb 13 10:52:30 volumio sudo[4290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:30 volumio sudo[4288]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "alsa_controller"... Feb 13 10:52:30 volumio sudo[4290]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:30 volumio sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 10:52:30 volumio sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:30 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "wizard"... Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "networkfs"... Feb 13 10:52:30 volumio volumio[4242]: info: Starting Udev Watcher for removable devices Feb 13 10:52:30 volumio volumio[4242]: info: Ignoring mount for partition: boot Feb 13 10:52:30 volumio volumio[4242]: info: Ignoring mount for partition: volumio Feb 13 10:52:30 volumio volumio[4242]: info: Ignoring mount for partition: volumio_data Feb 13 10:52:30 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "volumio_command_line_client"... Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "upnp"... Feb 13 10:52:30 volumio volumio[4242]: info: [1770954750538] Starting Upmpd Daemon Feb 13 10:52:30 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "my_music"... Feb 13 10:52:30 volumio volumio[4242]: info: Loading plugin "mpd"... Feb 13 10:52:31 volumio volumio[4242]: info: Loading plugin "upnp_browser"... Feb 13 10:52:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 10:52:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:32 volumio go-librespot[4321]: go-librespot daemon starting... Feb 13 10:52:32 volumio go-librespot[4322]: time="2026-02-13T10:52:32+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:32 volumio go-librespot[4322]: time="2026-02-13T10:52:32+07:00" level=debug msg="app state loaded" Feb 13 10:52:32 volumio go-librespot[4322]: time="2026-02-13T10:52:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:32 volumio sudo[4298]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=info msg="zeroconf server listening on port 46185" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="obtained new client token: AAB8PeIZbSnA/uiGro0vBXkatuCpBr4AFfEMmI1zyI7kSqOVj5spBNgEkpju/1TyglJZPiCAd27NcekJdzsxg81ijvKXUgMgOnBJG9gXlaxWRn4osWCOvmYXgA8uMMKvN+lKKPW6ySq/jlI0DyEeoKUhkp4PItZ8H9HSPpAB1g6Xf85hSRB070teuuvmC4FSlY2NEqVciZiFn1Q/bfGtkQDO/+TwDiiWzlarKl9AsZe4jozb73v3+gDDmg==" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=debug msg="completed challenge" Feb 13 10:52:33 volumio go-librespot[4322]: time="2026-02-13T10:52:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:34 volumio volumio[4242]: info: Starting UPNP Browser Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "alarm-clock"... Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "airplay_emulation"... Feb 13 10:52:34 volumio volumio[4242]: info: Starting Shairport Sync Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "last_100"... Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "webradio"... Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "i2s_dacs"... Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "volumiodiscovery"... Feb 13 10:52:34 volumio volumio[4242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:52:34 volumio volumio[4242]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:52:34 volumio volumio[4242]: *** WARNING *** For more information see Feb 13 10:52:34 volumio volumio[4242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:52:34 volumio volumio[4242]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:52:34 volumio volumio[4242]: *** WARNING *** For more information see Feb 13 10:52:34 volumio node[4242]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:52:34 volumio node[4242]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:52:34 volumio node[4242]: *** WARNING *** For more information see Feb 13 10:52:34 volumio node[4242]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:52:34 volumio node[4242]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:52:34 volumio node[4242]: *** WARNING *** For more information see Feb 13 10:52:34 volumio volumio[4242]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 10:52:34 volumio volumio[4242]: info: Discovery: Started advertising with name: Volumio Feb 13 10:52:34 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:52:34 volumio volumio[4242]: info: Loading plugin "spop"... Feb 13 10:52:36 volumio volumio[4242]: info: Loading plugin "ytcr"... Feb 13 10:52:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 10:52:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:37 volumio go-librespot[4332]: go-librespot daemon starting... Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=debug msg="app state loaded" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=info msg="zeroconf server listening on port 39533" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=debug msg="obtained new client token: AAAH+XxkDJYQ4csdyHexecdWQ54xy5hiDcSmwsrkj+B17szQc5C8AmTesqrJ6tzkMlIdpCKKf2OX3lxXaOy/b+lJH5kybE7GD5CknaDMlcu130GdGB2keRkTik4Dh3bOAJpjoG2cPd41CARt9jtbX6/woV9WS/YIstsTG6iHe2oSSOdyfQXNoF1PMPbcMA8q8CoB3M1jEkhzwO1py1//AFkZVAxLLKLZgEz3txtCcezSTlCzTne9zXwNYQ==" Feb 13 10:52:37 volumio go-librespot[4333]: time="2026-02-13T10:52:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:38 volumio go-librespot[4333]: time="2026-02-13T10:52:38+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:38 volumio go-librespot[4333]: time="2026-02-13T10:52:38+07:00" level=debug msg="completed challenge" Feb 13 10:52:38 volumio go-librespot[4333]: time="2026-02-13T10:52:38+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:39 volumio volumio[4242]: info: Loading plugin "ytmusic"... Feb 13 10:52:40 volumio volumio-remote-updater[643]: [2026-02-13 10:52:40] [connect] Successful connection Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "outputs"... Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "albumart"... Feb 13 10:52:40 volumio volumio[4242]: info: Plugin example_plugin is not enabled Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "inputs"... Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "updater_comm"... Feb 13 10:52:40 volumio volumio[4242]: info: Plugin mpdemulation is not enabled Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "rest_api"... Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "websocket"... Feb 13 10:52:40 volumio volumio[4242]: info: Starting Socket.io Server version 1.7.4 Feb 13 10:52:40 volumio volumio[4242]: info: Loading plugin "RoonBridge"... Feb 13 10:52:41 volumio volumio[4242]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 10:52:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 10:52:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:41 volumio volumio[4242]: info: Loading i18n strings for locale en Feb 13 10:52:41 volumio volumio[4242]: Updating browse sources language Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:41 volumio go-librespot[4364]: go-librespot daemon starting... Feb 13 10:52:41 volumio go-librespot[4365]: time="2026-02-13T10:52:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:41 volumio go-librespot[4365]: time="2026-02-13T10:52:41+07:00" level=debug msg="app state loaded" Feb 13 10:52:41 volumio go-librespot[4365]: time="2026-02-13T10:52:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:41 volumio volumio[4354]: Forking 3 albumart workers Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::initPlayerControls Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:52:41 volumio volumio[4242]: Express server listening on port 3000 Feb 13 10:52:41 volumio volumio[4242]: [Metrics] WebUI: 16s 99.57ms Feb 13 10:52:41 volumio volumio[4242]: info: CoreStateMachine::resetVolumioState Feb 13 10:52:41 volumio volumio[4242]: info: CoreStateMachine::getcurrentVolume Feb 13 10:52:41 volumio volumio[4242]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:52:41 volumio sudo[4406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:52:41 volumio sudo[4406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:41 volumio sudo[4403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:52:41 volumio sudo[4403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:42 volumio sudo[4403]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:42 volumio sudo[4406]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:42 volumio volumio[4242]: info: Volumio Network Manager: Network status updated: 1 Feb 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+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 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+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 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=info msg="zeroconf server listening on port 43731" Feb 13 10:52:42 volumio volumio[4242]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::pushState Feb 13 10:52:42 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::volumioPushState Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::updateTrackBlock Feb 13 10:52:42 volumio volumio[4242]: info: CorePlayQueue::getTrackBlock Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=debug msg="obtained new client token: AAD/lkMYc0Ver1JerIjOVmkliCqmsiHxvsl30QWIh24HAMgLtXJMfAYeD7Gnc9Q1uKsn/cs7tDYszWO4CtWhrC9RVxgRA+738zCBRtYU9kKJcKSI3LeMxMI5tkmcOLtwKGEIwNDcdDmoqR1KOls48ms/wSautCweY3UEzzwzMlPsKwoCkmyW02Qc8nK9ZFbWAy4rdxK8wS4IQHVSZuyNkIVemJxuj95oitJDrDJ7hgOt85RPRP9Gadj1aw==" Feb 13 10:52:42 volumio volumio-remote-updater[643]: [2026-02-13 10:52:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770954760 101 Feb 13 10:52:42 volumio volumio[4242]: 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 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:42 volumio volumio[4242]: info: Reloading queue from file Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::setRepeat null single undefined Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::pushState Feb 13 10:52:42 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::volumioPushState Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::setRandom null Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::pushState Feb 13 10:52:42 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::volumioPushState Feb 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=debug msg="completed challenge" Feb 13 10:52:42 volumio volumio[4242]: info: Setting Device type: Raspberry PI Feb 13 10:52:42 volumio volumio[4242]: info: Completed loading Core Plugins Feb 13 10:52:42 volumio volumio[4242]: info: Preparing to generate the ALSA configuration file Feb 13 10:52:42 volumio go-librespot[4365]: time="2026-02-13T10:52:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:42 volumio volumio[4242]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:52:42 volumio volumio[4242]: info: CoreStateMachine::pushState Feb 13 10:52:42 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::volumioPushState Feb 13 10:52:42 volumio volumio[4242]: info: Asound.conf file unchanged, so no further update is needed Feb 13 10:52:42 volumio volumio[4242]: info: Output device has changed, restarting MPD Feb 13 10:52:42 volumio volumio[4242]: info: Output device has changed, restarting Shairport Sync Feb 13 10:52:42 volumio sudo[4422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:42 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:42 volumio sudo[4422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:42 volumio sudo[4424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:52:42 volumio sudo[4424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:42 volumio sudo[4424]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:43 volumio sudo[4426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:52:43 volumio sudo[4426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:43 volumio volumio[4242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:52:43 volumio volumio[4242]: info: ___________ START PLUGINS ___________ Feb 13 10:52:43 volumio sudo[4422]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:43 volumio volumio[4242]: info: ControllerMpd::onStart: Initializing MPD Feb 13 10:52:43 volumio volumio[4242]: info: Creating MPD Configuration file Feb 13 10:52:43 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 10:52:43 volumio sudo[4435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:52:43 volumio sudo[4435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:43 volumio sudo[4435]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:43 volumio volumio[4242]: info: [1770954763349] CoreMusicLibrary::Adding element Media Servers Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:43 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:52:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:52:43 volumio systemd[1]: mpd.service: Consumed 7.189s CPU time. Feb 13 10:52:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:52:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:52:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:52:43 volumio sudo[4438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:52:43 volumio sudo[4438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:43 volumio volumio[4242]: info: UPNP Browser: Client initialized successfully Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:52:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:52:43 volumio volumio[4242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:43 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:52:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:52:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:52:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:52:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:52:43 volumio volumio[4242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:43 volumio volumio[4242]: info: [1770954763634] CoreMusicLibrary::Adding element Last_100 Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:43 volumio volumio[4242]: info: [1770954763653] CoreMusicLibrary::Adding element Webradio Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:52:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:52:43 volumio volumio[4242]: info: Initializing BBC Radios Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:43 volumio volumio[4242]: info: Creating Spotify config file Feb 13 10:52:43 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:44 volumio sudo[4451]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 10:52:44 volumio sudo[4451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 10:52:44 volumio sudo[4451]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:44 volumio volumio[4371]: Starting albumart workers Feb 13 10:52:44 volumio volumio[4242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:44 volumio volumio[4242]: info: [1770954764884] CoreMusicLibrary::Adding element YouTube Music Feb 13 10:52:44 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:44 volumio volumio[4242]: Cannot find translation for source YouTube Music Feb 13 10:52:44 volumio volumio[4373]: Starting albumart workers Feb 13 10:52:44 volumio volumio[4242]: info: Volumio Calling Home Feb 13 10:52:45 volumio sudo[4468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 10:52:45 volumio sudo[4468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:45 volumio sudo[4468]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:45 volumio volumio[4372]: Starting albumart workers Feb 13 10:52:45 volumio volumio[4242]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:52:45 volumio volumio[4242]: info: Discovery: Found device Volumio Feb 13 10:52:45 volumio volumio[4242]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:45 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:45 volumio volumio[4242]: info: MPD Permissions set Feb 13 10:52:45 volumio volumio[4242]: info: MPD Permissions set Feb 13 10:52:45 volumio volumio[4242]: info: Upmpdcli Daemon Started Feb 13 10:52:45 volumio volumio[4242]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:52:45 volumio volumio[4242]: info: Discovery: Found device Volumio Feb 13 10:52:45 volumio volumio[4242]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:45 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 10:52:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:45 volumio volumio[4242]: info: Spotify config file written Feb 13 10:52:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:45 volumio go-librespot[4473]: go-librespot daemon starting... Feb 13 10:52:45 volumio sudo[4476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 10:52:45 volumio sudo[4476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:46 volumio go-librespot[4475]: time="2026-02-13T10:52:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:46 volumio go-librespot[4475]: time="2026-02-13T10:52:46+07:00" level=debug msg="app state loaded" Feb 13 10:52:46 volumio go-librespot[4475]: time="2026-02-13T10:52:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:46 volumio volumio[4242]: 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 13 10:52:46 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 10:52:46 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 10:52:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:46 volumio volumio[4242]: info: Volumio called home Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio go-librespot[4484]: go-librespot daemon starting... Feb 13 10:52:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:46 volumio sudo[4476]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:46 volumio go-librespot[4490]: time="2026-02-13T10:52:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:46 volumio go-librespot[4490]: time="2026-02-13T10:52:46+07:00" level=debug msg="app state loaded" Feb 13 10:52:46 volumio go-librespot[4490]: time="2026-02-13T10:52:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:52:46 volumio volumio[4242]: info: No need to fix Spotify hosts Feb 13 10:52:46 volumio go-librespot[4490]: time="2026-02-13T10:52:46+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:52:46 volumio go-librespot[4490]: time="2026-02-13T10:52:46+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 13 10:52:46 volumio go-librespot[4490]: time="2026-02-13T10:52:46+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 13 10:52:47 volumio go-librespot[4490]: time="2026-02-13T10:52:47+07:00" level=info msg="zeroconf server listening on port 36905" Feb 13 10:52:47 volumio go-librespot[4490]: time="2026-02-13T10:52:47+07:00" level=debug msg="obtained new client token: AABYnFLS0+syCi8MMe2GHExMvRt3zea+GWg4WWBpD7UcOUZZ+pN5owOd8NevFaOI3itPpMjmvceYtse4PmVtMzgZfg43Yh+uQq9QzTPW6hgdClWLiLvzXLHObvXb70X/ZJ0hJsf3AVG/JdQo1P1pNOLfcVnnDwdZ48HojFz7NN/bs7ytnAOvhBf3Hd/W1Um5oExzYdQSWtyRCKeO//2dNHJXNFMIxiBOxfz/9gi/OK7cEAtgJDfs2wU=" Feb 13 10:52:47 volumio go-librespot[4490]: time="2026-02-13T10:52:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:47 volumio go-librespot[4490]: time="2026-02-13T10:52:47+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:47 volumio go-librespot[4490]: time="2026-02-13T10:52:47+07:00" level=debug msg="completed challenge" Feb 13 10:52:47 volumio go-librespot[4490]: time="2026-02-13T10:52:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:47 volumio volumio[4242]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:52:47 volumio volumio[4242]: SPOTIFY: BQDzt2dMpgKtJTiboIs43rl568jjed7oIOOlaQE0y3SDaxDcEzeQeztlyJRSvH18VlqgwamQMLM2nu4JFhKVcG02A8wcv1sf3sj6EqAlrCj9uFX9e3BAzpX5s_i4FZNimwrC3--X4_avonsKtUOmmBIwAFfPiK4GDSfiqkAuEO_blposabzdjLJf78rHM-enXfd6AS9IocnBMTTjND2u3k58Uoy4Js0tx3Z-Fvm43dN8qQ4yNsUSgTldcvpmqkajcAXDnb1FgNXnP_OBqZBdV19TLWEcTnAdgOUkMux3dCKEypvUsZ_TIocl Feb 13 10:52:47 volumio volumio[4242]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:52:47 volumio volumio[4242]: info: New Spotify access token = BQDzt2dMpgKtJTiboIs43rl568jjed7oIOOlaQE0y3SDaxDcEzeQeztlyJRSvH18VlqgwamQMLM2nu4JFhKVcG02A8wcv1sf3sj6EqAlrCj9uFX9e3BAzpX5s_i4FZNimwrC3--X4_avonsKtUOmmBIwAFfPiK4GDSfiqkAuEO_blposabzdjLJf78rHM-enXfd6AS9IocnBMTTjND2u3k58Uoy4Js0tx3Z-Fvm43dN8qQ4yNsUSgTldcvpmqkajcAXDnb1FgNXnP_OBqZBdV19TLWEcTnAdgOUkMux3dCKEypvUsZ_TIocl Feb 13 10:52:47 volumio volumio[4242]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 10:52:47 volumio volumio[4242]: info: Starting Shairport Sync Feb 13 10:52:47 volumio volumio[4242]: info: Starting Shairport Sync Feb 13 10:52:47 volumio volumio[4242]: info: Starting Shairport Sync Feb 13 10:52:47 volumio sudo[4521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:52:47 volumio sudo[4521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:48 volumio sudo[4523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:52:48 volumio sudo[4523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:48 volumio sudo[4525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:52:48 volumio sudo[4525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:52:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 10:52:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 10:52:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:52:48 volumio systemd[1]: shairport-sync.service: Consumed 2.280s CPU time. Feb 13 10:52:48 volumio volumio[4242]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:48 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:52:48 volumio sudo[4521]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:48 volumio sudo[4523]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:48 volumio sudo[4525]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:48 volumio volumio[4242]: info: Shairport-Sync Started Feb 13 10:52:48 volumio volumio[4242]: Error adding Membership: Error: addMembership EINVAL Feb 13 10:52:48 volumio volumio[4242]: info: Shairport-Sync Started Feb 13 10:52:48 volumio volumio[4242]: info: Shairport-Sync Started Feb 13 10:52:48 volumio volumio[4242]: 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 13 10:52:48 volumio volumio[4242]: info: Spotify Successfully logged in Feb 13 10:52:48 volumio volumio[4242]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:52:48 volumio volumio[4242]: info: [1770954768804] CoreMusicLibrary::Adding element Spotify Feb 13 10:52:48 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:52:48 volumio volumio[4242]: Cannot find translation for source YouTube Music Feb 13 10:52:48 volumio volumio[4242]: Cannot find translation for source Spotify Feb 13 10:52:49 volumio volumio[4242]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 10:52:49 volumio volumio[4242]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:52:49 volumio volumio[4242]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:52:49 volumio volumio[4242]: info: CoreCommandRouter::volumioGetState Feb 13 10:52:49 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:49 volumio volumio[4242]: info: CoreStateMachine::pushState Feb 13 10:52:49 volumio volumio[4242]: info: CorePlayQueue::getTrack 0 Feb 13 10:52:49 volumio volumio[4242]: info: CoreCommandRouter::volumioPushState Feb 13 10:52:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 10:52:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:50 volumio volumio[4242]: info: go-librespot daemon successfully initialized Feb 13 10:52:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:50 volumio go-librespot[4561]: go-librespot daemon starting... Feb 13 10:52:50 volumio go-librespot[4562]: time="2026-02-13T10:52:50+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:50 volumio go-librespot[4562]: time="2026-02-13T10:52:50+07:00" level=debug msg="app state loaded" Feb 13 10:52:50 volumio go-librespot[4562]: time="2026-02-13T10:52:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:51 volumio mpd[4466]: 2026-02-13T10:52:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 10:52:51 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 10:52:51 volumio sudo[4438]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:51 volumio sudo[4426]: pam_unix(sudo:session): session closed for user root Feb 13 10:52:51 volumio volumio[4242]: error: MPD error: The expression evaluated to a falsy value: Feb 13 10:52:51 volumio volumio[4242]: assert.ok(self.idling) Feb 13 10:52:51 volumio volumio[4242]: error: The expression evaluated to a falsy value: Feb 13 10:52:51 volumio volumio[4242]: assert.ok(self.idling) Feb 13 10:52:51 volumio volumio[4242]: info: MPD running with PID4466 Feb 13 10:52:51 volumio volumio[4242]: ,establishing connection Feb 13 10:52:51 volumio volumio[4242]: error: updateQueue error: null Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+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 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+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 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+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 13 10:52:51 volumio volumio[4242]: info: Completed starting Core Plugins Feb 13 10:52:51 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:51 volumio volumio[4242]: info: ----- MyVolumio plugins startup ---- Feb 13 10:52:51 volumio volumio[4242]: info: ------------------------------------------- Feb 13 10:52:51 volumio volumio[4242]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 10:52:51 volumio volumio[4242]: error: updateQueue error: null Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+07:00" level=info msg="zeroconf server listening on port 37859" Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+07:00" level=debug msg="obtained new client token: AACT1Y4ezvGsicKb3ctd1tyd7JPjiO55OSGbzELmgDl+iTM+0yMDCMkGCbHvdwObfYKrwPqyUJEcZGO90J0r91/9lRMpYpmWlcIA3n0QRVaQZs0cFnYEhXUjDzybIVnZR2L9pSBd88/nFIWC3ckng9xfunLJIXxoOcbmCM+fSYbBmRaZSdB5QcjAj9x9isy0Ak8hBr5XXFIER67aNWFPxQs45vlBaVNhJxqQjc/Ooxy+0W1RPqefxcUSoQ==" Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:51 volumio go-librespot[4562]: time="2026-02-13T10:52:51+07:00" level=debug msg="completed challenge" Feb 13 10:52:52 volumio go-librespot[4562]: time="2026-02-13T10:52:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:53 volumio volumio[4242]: info: Initializing connection to go-librespot Websocket Feb 13 10:52:53 volumio volumio[4242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:52:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 10:52:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:55 volumio go-librespot[4573]: go-librespot daemon starting... Feb 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+07:00" level=debug msg="app state loaded" Feb 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+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 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+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 13 10:52:55 volumio go-librespot[4574]: time="2026-02-13T10:52:55+07:00" level=info msg="zeroconf server listening on port 37769" Feb 13 10:52:56 volumio go-librespot[4574]: time="2026-02-13T10:52:56+07:00" level=debug msg="obtained new client token: AABGTCjYuSIhSwt/nBtNq9A7MITMup/DMII9W8pUSqzW6evy6VoE3NlBmWGlmIMUmR5MQCW+Yw+rINOFIMNKHlxbUNXcXJ0ILM3cOBjqmJcAGkXLvwqwZV99zymkhnAcEAQh8CFCv89z5UJI8fk2v77/A2JYcFmjep26uUOIdtQ10VdAJBkkjjAf5LqemYmueULY3vzzqRo47SYe3/kHxQ3UuEAbqPqiSBm9IqB4OVhcTitb4GPyYDQ=" Feb 13 10:52:56 volumio volumio[4242]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 10:52:56 volumio go-librespot[4574]: time="2026-02-13T10:52:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:52:56 volumio go-librespot[4574]: time="2026-02-13T10:52:56+07:00" level=debug msg="completed keyexchange" Feb 13 10:52:56 volumio go-librespot[4574]: time="2026-02-13T10:52:56+07:00" level=debug msg="completed challenge" Feb 13 10:52:56 volumio go-librespot[4574]: time="2026-02-13T10:52:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:52:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:52:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:52:56 volumio volumio[4242]: info: Initializing connection to go-librespot Websocket Feb 13 10:52:56 volumio volumio[4242]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:52:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 10:52:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:52:59 volumio go-librespot[4582]: go-librespot daemon starting... Feb 13 10:52:59 volumio go-librespot[4583]: time="2026-02-13T10:52:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:52:59 volumio go-librespot[4583]: time="2026-02-13T10:52:59+07:00" level=debug msg="app state loaded" Feb 13 10:52:59 volumio go-librespot[4583]: time="2026-02-13T10:52:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:52:59 volumio volumio[4242]: info: Initializing connection to go-librespot Websocket Feb 13 10:52:59 volumio go-librespot[4583]: time="2026-02-13T10:52:59+07:00" level=debug msg="new websocket client" Feb 13 10:52:59 volumio volumio[4242]: info: Connection to go-librespot Websocket established Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 10:52:59 volumio volumio[4242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 10:52:59 volumio volumio[4242]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 10:52:59 volumio volumio[4242]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 10:52:59 volumio volumio[4242]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 10:52:59 volumio volumio[4242]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 10:52:59 volumio volumio[4242]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 10:52:59 volumio volumio[4242]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 10:53:00 volumio volumio[4242]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 10:53:00 volumio volumio[4242]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+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 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+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 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=info msg="zeroconf server listening on port 43673" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="obtained new client token: AAAolmq5EMka4oRWXj1Kpaq3wkJ1GrbIHv/HKjVBl+orzmADrYbFM1qfnFJW/fGPN4wNYKnuwuZGk6JyB16oIH2D7Z4Z0u2rgIEQDXlc6TbIFCEkqgfh3SX0gWBqPEzHLf8UOjx2R6a7n3EYglfLeIZ/IJOZe3ENcuMRD4G3nTEIqgjxQPdduOXYvUPSnnAuYNeSA4u/JgHo6axUw+9z+ggs0PeM8q/Caj1AgSkFi1UAnpB4HuzWAZFoiA==" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=debug msg="completed challenge" Feb 13 10:53:00 volumio go-librespot[4583]: time="2026-02-13T10:53:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 10:53:01 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:01 volumio volumio[4242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:01 volumio volumio[4242]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 10:53:01 volumio volumio[4242]: info: MyVolumio login type: Token Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 10:53:01 volumio volumio[4242]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 10:53:03 volumio volumio[4242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 10:53:03 volumio volumio[4242]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 10:53:03 volumio volumio[4242]: info: Streaming services startup Feb 13 10:53:03 volumio volumio[4242]: info: Starting Streaming Daemon Feb 13 10:53:03 volumio sudo[4606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:53:03 volumio sudo[4606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:03 volumio volumio[4242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 10:53:03 volumio sudo[4606]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:03 volumio volumio[4242]: info: Getting Spotify volume Feb 13 10:53:03 volumio volumio[4242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:03 volumio volumio[4242]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:53:03 volumio volumio[4242]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 13 10:53:03 volumio volumio[4242]: errno: -111, Feb 13 10:53:03 volumio volumio[4242]: code: 'ECONNREFUSED', Feb 13 10:53:03 volumio volumio[4242]: syscall: 'connect', Feb 13 10:53:03 volumio volumio[4242]: address: '127.0.0.1', Feb 13 10:53:03 volumio volumio[4242]: port: 9879, Feb 13 10:53:03 volumio volumio[4242]: response: undefined Feb 13 10:53:03 volumio volumio[4242]: } Feb 13 10:53:03 volumio volumio[4242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 10:53:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:04 volumio go-librespot[4623]: go-librespot daemon starting... Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="app state loaded" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+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 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+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 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=info msg="zeroconf server listening on port 33151" Feb 13 10:53:04 volumio go-librespot[4625]: time="2026-02-13T10:53:04+07:00" level=debug msg="obtained new client token: AAAf+6V2UvHT6dmFE0DEhXzyJ720g7mlBkQuJ2tklRIFUTie8z1jAD99nCwiu/EHVuVk817qiEvvVARYH6Z8yNmxFTx9/DjwQRIm6KEkAza0eBvRtz6d89Dx1UyLXaK20QZacWC8zXdGErQpYvDi348tmqgDF6p9wHIqa/SaZ/6Wec9213tLfCn/I0qCjeU5jYVYfK+C3oXGYZ0oi3Ic2pXLfDuSrJX2CQt4mgdgLc+sHg6/SAW1LCsoVQ==" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+07:00" level=debug msg="completed challenge" Feb 13 10:53:05 volumio go-librespot[4625]: time="2026-02-13T10:53:05+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 13 10:53:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:05 volumio sudo[4634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 10:52' Feb 13 10:53:05 volumio sudo[4634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:05 volumio sudo[4634]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:07 volumio volumio-remote-updater[643]: [2026-02-13 10:53:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 13 10:53:07 volumio volumio-remote-updater[643]: [2026-02-13 10:53:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 13 10:53:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 10:53:07 volumio systemd[1]: volumio.service: Consumed 55.058s CPU time. Feb 13 10:53:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:53:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:53:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14594. Feb 13 10:53:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 10:53:07 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 10:53:07 volumio systemd[1]: volumio.service: Consumed 55.058s CPU time. Feb 13 10:53:07 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 10:53:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 10:53:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 10:53:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:08 volumio go-librespot[4666]: go-librespot daemon starting... Feb 13 10:53:08 volumio go-librespot[4667]: time="2026-02-13T10:53:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:08 volumio go-librespot[4667]: time="2026-02-13T10:53:08+07:00" level=debug msg="app state loaded" Feb 13 10:53:08 volumio go-librespot[4667]: time="2026-02-13T10:53:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+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 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+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 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=info msg="zeroconf server listening on port 34221" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="obtained new client token: AAA3X6yiRH2bqx7dAqbLIuiVj8rv+i0n2u+0nY5V0byVk7j8CTCmHKextRBQVxjndnPMfNMwlvxaqPISw9Hb8Q4OG7OGNkWDtaXYzdeXvVJUeQqlV32mwJf4oUc6qweoyK3J1LWCjkRU1kfiMMa87IzzvmIx6ILsaLCapeWrKT3yigv1U/MxCGt6HYSNQ0DVYNsr9nnt8RNYNpmBsjV5Fx2VV1MqNfNjAtEe0F80UFPkWVCXNvPw+VIJrQ==" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=debug msg="completed challenge" Feb 13 10:53:09 volumio go-librespot[4667]: time="2026-02-13T10:53:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:11 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:11 volumio volumio[4651]: info: ----- Volumio3 ---- Feb 13 10:53:11 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:11 volumio volumio[4651]: info: ----- System startup ---- Feb 13 10:53:11 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:12 volumio volumio-remote-updater[643]: [2026-02-13 10:53:12] [connect] Successful connection Feb 13 10:53:12 volumio volumio[4651]: info: MYVOLUMIO Environment detected Feb 13 10:53:12 volumio volumio[4651]: info: Plugin folders cleanup Feb 13 10:53:12 volumio volumio[4651]: info: Scanning into folder /volumio/app/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category audio_interface Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category miscellanea Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category music_service Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category plugins.json Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category system_controller Feb 13 10:53:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category user_interface Feb 13 10:53:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:12 volumio volumio[4651]: info: Scanning into folder /data/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Scanning category music_service Feb 13 10:53:12 volumio volumio[4651]: info: Plugin folders cleanup completed Feb 13 10:53:12 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:12 volumio volumio[4651]: info: ----- Core plugins startup ---- Feb 13 10:53:12 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Adding plugin upnp to MyMusic Plugins Feb 13 10:53:12 volumio volumio[4651]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 10:53:12 volumio volumio[4651]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugins from folder /data/plugins/ Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugin "system"... Feb 13 10:53:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:12 volumio go-librespot[4684]: go-librespot daemon starting... Feb 13 10:53:12 volumio volumio[4651]: info: Loading plugin "appearance"... Feb 13 10:53:12 volumio go-librespot[4685]: time="2026-02-13T10:53:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:12 volumio go-librespot[4685]: time="2026-02-13T10:53:12+07:00" level=debug msg="app state loaded" Feb 13 10:53:12 volumio go-librespot[4685]: time="2026-02-13T10:53:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+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 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+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 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=info msg="zeroconf server listening on port 38621" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="obtained new client token: AABR/CvxkI/CKcf/lYViD4BBlxqJVG9FNOrx9y8GFBqNhUXyM6P9AR3IKD8A5PxJQPOvSCKAaoLMtQcHj+qpHCTS8AOzVCnph/8z0kl4zSQrA937KrsTapwIUQEtzA68hprp8RX2DJn52Ef4DNuNxnVytsZNo7rRvLro+k/bqPN+ULZDuxwd4FzjJoIGMTYKtGl0xyq5KwPD0aiYfaVBztPrWhFLPL8G/gI2D0mqh8OSsnWvpg1E40SQiQ==" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:13 volumio go-librespot[4685]: time="2026-02-13T10:53:13+07:00" level=debug msg="completed challenge" Feb 13 10:53:14 volumio go-librespot[4685]: time="2026-02-13T10:53: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 13 10:53:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:14 volumio volumio[4651]: info: Loading plugin "network"... Feb 13 10:53:14 volumio volumio[4651]: info: Refreshing Cached IP Addresses Feb 13 10:53:14 volumio sudo[4696]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:53:14 volumio sudo[4696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:14 volumio sudo[4696]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:14 volumio sudo[4698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:53:14 volumio volumio[4651]: info: Loading plugin "services"... Feb 13 10:53:14 volumio volumio[4651]: info: Loading plugin "alsa_controller"... Feb 13 10:53:14 volumio sudo[4698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:15 volumio sudo[4698]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:15 volumio sudo[4706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 10:53:15 volumio sudo[4706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:15 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "wizard"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "networkfs"... Feb 13 10:53:15 volumio volumio[4651]: info: Starting Udev Watcher for removable devices Feb 13 10:53:15 volumio volumio[4651]: info: Ignoring mount for partition: boot Feb 13 10:53:15 volumio volumio[4651]: info: Ignoring mount for partition: volumio Feb 13 10:53:15 volumio volumio[4651]: info: Ignoring mount for partition: volumio_data Feb 13 10:53:15 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "volumio_command_line_client"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "upnp"... Feb 13 10:53:15 volumio volumio[4651]: info: [1770954795281] Starting Upmpd Daemon Feb 13 10:53:15 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "my_music"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "mpd"... Feb 13 10:53:15 volumio volumio[4651]: info: Loading plugin "upnp_browser"... Feb 13 10:53:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 10:53:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:17 volumio go-librespot[4730]: go-librespot daemon starting... Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="app state loaded" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+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 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+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 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+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 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=info msg="zeroconf server listening on port 45839" Feb 13 10:53:17 volumio sudo[4706]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="obtained new client token: AADCjYCFj0Jvz63XkbfpDjPC6wf8uNPF12ESkbgw5tK4/ll+sEodKMrEwBZaheB7Uk53ov8l80X1w5AbltbDVS3FJx8yIuP3rUqfTXA97A+2MzIPVjIhaSePUI3N/l16KM1HCZm3eIRHJ+Nabyc/9TEG4E7nK2CQyclBKylamH49IA+t/GgKVHgzvjqubK0kFMq0rB0VQ56iBC4RScOLWlqC7exHttb6H/diL6q6uKsNytAOD5oFXtLagg==" Feb 13 10:53:17 volumio go-librespot[4731]: time="2026-02-13T10:53:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:18 volumio go-librespot[4731]: time="2026-02-13T10:53:18+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:18 volumio go-librespot[4731]: time="2026-02-13T10:53:18+07:00" level=debug msg="completed challenge" Feb 13 10:53:18 volumio go-librespot[4731]: time="2026-02-13T10:53:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:18 volumio volumio[4651]: info: Starting UPNP Browser Feb 13 10:53:18 volumio volumio[4651]: info: Loading plugin "alarm-clock"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "airplay_emulation"... Feb 13 10:53:19 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "last_100"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "webradio"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "i2s_dacs"... Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "volumiodiscovery"... Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio node[4651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio volumio[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio node[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio node[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio node[4651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 10:53:19 volumio node[4651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 10:53:19 volumio node[4651]: *** WARNING *** For more information see Feb 13 10:53:19 volumio volumio[4651]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 10:53:19 volumio volumio[4651]: info: Discovery: Started advertising with name: Volumio Feb 13 10:53:19 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 10:53:19 volumio volumio[4651]: info: Loading plugin "spop"... Feb 13 10:53:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 10:53:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:21 volumio volumio[4651]: info: Loading plugin "ytcr"... Feb 13 10:53:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:21 volumio go-librespot[4741]: go-librespot daemon starting... Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="app state loaded" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:21 volumio go-librespot[4742]: time="2026-02-13T10:53:21+07:00" level=info msg="zeroconf server listening on port 45121" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="obtained new client token: AABzOT/Buzxu5VWkeduy5Jjj4UqAj5tlfhDsZqvtMvJ1TBCEWWFjEW16EZsW1D/9OnTBLEPJDwRJqIKqIbPgYAs2QjW2dwXPe2ZOgGYPeVEhHZ1fk2T9GNS+H3mq0Lq5nbiihyaR1btIs6krk+mRAckQPAtABmsyPkhfJa9vxWf6blJ/F6iVS5+Yjn/6AXwQLLFfIN4+2rL1OilrszHZ6XeZS00rxvi4o2NKCSjMhspN6+FhqutboiI=" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=debug msg="completed challenge" Feb 13 10:53:22 volumio go-librespot[4742]: time="2026-02-13T10:53:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:24 volumio volumio[4651]: info: Loading plugin "ytmusic"... Feb 13 10:53:24 volumio volumio-remote-updater[643]: [2026-02-13 10:53:24] [connect] Successful connection Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "outputs"... Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "albumart"... Feb 13 10:53:25 volumio volumio[4651]: info: Plugin example_plugin is not enabled Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "inputs"... Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "updater_comm"... Feb 13 10:53:25 volumio volumio[4651]: info: Plugin mpdemulation is not enabled Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "rest_api"... Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "websocket"... Feb 13 10:53:25 volumio volumio[4651]: info: Starting Socket.io Server version 1.7.4 Feb 13 10:53:25 volumio volumio[4651]: info: Loading plugin "RoonBridge"... Feb 13 10:53:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 10:53:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:25 volumio go-librespot[4773]: go-librespot daemon starting... Feb 13 10:53:25 volumio go-librespot[4774]: time="2026-02-13T10:53:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:25 volumio go-librespot[4774]: time="2026-02-13T10:53:25+07:00" level=debug msg="app state loaded" Feb 13 10:53:25 volumio go-librespot[4774]: time="2026-02-13T10:53:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:26 volumio volumio[4651]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 10:53:26 volumio volumio[4651]: info: Loading i18n strings for locale en Feb 13 10:53:26 volumio volumio[4651]: Updating browse sources language Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=info msg="zeroconf server listening on port 38943" Feb 13 10:53:26 volumio volumio[4763]: Forking 3 albumart workers Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::initPlayerControls Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:53:26 volumio volumio[4651]: Express server listening on port 3000 Feb 13 10:53:26 volumio volumio[4651]: [Metrics] WebUI: 16s 109.36ms Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="obtained new client token: AAAPkwA0o696vASmNTTEOattIN+JFZyMldXYSabiQS5KYRE0rUGzeyjpZKWXUj5GqioxzvZd4ZzCKlsIQpTGb9HRy/joabMLHM30YOaImrRTqmKowK+T2Z4UIt3pLFwWDQ8/s/jYIfdNSncRdkdZKFyZk4bjB/6HGFV7/8ZaFS2CaXkrrqQ7/wjuO32+jBR2huen9kbmyzh73A3HjdT2DmAHvy5wcWcnofYzPIeWq6nFCqF7MOS2/PMLoA==" Feb 13 10:53:26 volumio volumio[4651]: info: CoreStateMachine::resetVolumioState Feb 13 10:53:26 volumio volumio[4651]: info: CoreStateMachine::getcurrentVolume Feb 13 10:53:26 volumio volumio[4651]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:26 volumio sudo[4815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 10:53:26 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=debug msg="completed challenge" Feb 13 10:53:26 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:26 volumio sudo[4817]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 10:53:26 volumio sudo[4817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:26 volumio sudo[4817]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:26 volumio go-librespot[4774]: time="2026-02-13T10:53:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:26 volumio volumio[4651]: info: Volumio Network Manager: Network status updated: 1 Feb 13 10:53:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:27 volumio volumio[4651]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::updateTrackBlock Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrackBlock Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:53:27 volumio volumio-remote-updater[643]: [2026-02-13 10:53:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770954804 101 Feb 13 10:53:27 volumio volumio[4651]: 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 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:27 volumio volumio[4651]: info: Reloading queue from file Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::setRepeat null single undefined Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::setRandom null Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: Setting Device type: Raspberry PI Feb 13 10:53:27 volumio volumio[4651]: info: Completed loading Core Plugins Feb 13 10:53:27 volumio volumio[4651]: info: Preparing to generate the ALSA configuration file Feb 13 10:53:27 volumio volumio[4651]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:53:27 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:27 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:27 volumio volumio[4651]: info: Asound.conf file unchanged, so no further update is needed Feb 13 10:53:27 volumio volumio[4651]: info: Output device has changed, restarting MPD Feb 13 10:53:27 volumio volumio[4651]: info: Output device has changed, restarting Shairport Sync Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:27 volumio sudo[4832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 10:53:27 volumio sudo[4832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:53:27 volumio sudo[4834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:53:27 volumio sudo[4836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4834]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:27 volumio volumio[4651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:53:27 volumio volumio[4651]: info: ___________ START PLUGINS ___________ Feb 13 10:53:27 volumio volumio[4651]: info: ControllerMpd::onStart: Initializing MPD Feb 13 10:53:27 volumio volumio[4651]: info: Creating MPD Configuration file Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:27 volumio volumio[4651]: info: [1770954807886] CoreMusicLibrary::Adding element Media Servers Feb 13 10:53:27 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:27 volumio sudo[4845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 10:53:27 volumio sudo[4845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:27 volumio sudo[4845]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 10:53:27 volumio sudo[4832]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:27 volumio volumio[4651]: info: UPNP Browser: Client initialized successfully Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:28 volumio sudo[4847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 10:53:28 volumio sudo[4847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:53:28 volumio systemd[1]: mpd.service: Consumed 7.039s CPU time. Feb 13 10:53:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:53:28 volumio volumio[4651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio volumio[4651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 10:53:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:28 volumio volumio[4651]: info: [1770954808180] CoreMusicLibrary::Adding element Last_100 Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:28 volumio volumio[4651]: info: [1770954808195] CoreMusicLibrary::Adding element Webradio Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:53:28 volumio volumio[4651]: info: Initializing BBC Radios Feb 13 10:53:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 10:53:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 10:53:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 10:53:28 volumio volumio[4651]: info: Creating Spotify config file Feb 13 10:53:28 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 10:53:28 volumio sudo[4874]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 10:53:28 volumio sudo[4874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 10:53:28 volumio sudo[4874]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:29 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:29 volumio volumio[4651]: info: [1770954809477] CoreMusicLibrary::Adding element YouTube Music Feb 13 10:53:29 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:29 volumio volumio[4651]: Cannot find translation for source YouTube Music Feb 13 10:53:29 volumio volumio[4651]: info: Volumio Calling Home Feb 13 10:53:29 volumio volumio[4782]: Starting albumart workers Feb 13 10:53:29 volumio sudo[4878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 10:53:29 volumio sudo[4878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:29 volumio sudo[4878]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:29 volumio volumio[4781]: Starting albumart workers Feb 13 10:53:29 volumio volumio[4783]: Starting albumart workers Feb 13 10:53:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 10:53:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:30 volumio go-librespot[4883]: go-librespot daemon starting... Feb 13 10:53:30 volumio go-librespot[4884]: time="2026-02-13T10:53:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: Found device Volumio Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:30 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 10:53:30 volumio volumio[4651]: info: Discovery: Found device Volumio Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:30 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:30 volumio volumio[4651]: info: MPD Permissions set Feb 13 10:53:30 volumio volumio[4651]: info: MPD Permissions set Feb 13 10:53:30 volumio volumio[4651]: info: Upmpdcli Daemon Started Feb 13 10:53:30 volumio volumio[4651]: info: Volumio called home Feb 13 10:53:30 volumio volumio[4651]: info: Spotify config file written Feb 13 10:53:30 volumio sudo[4896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 10:53:30 volumio sudo[4896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:30 volumio volumio[4651]: 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 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 10:53:30 volumio volumio[4651]: info: No need to fix Spotify hosts Feb 13 10:53:31 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 10:53:31 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 10:53:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:31 volumio go-librespot[4911]: go-librespot daemon starting... Feb 13 10:53:31 volumio sudo[4896]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:31 volumio go-librespot[4912]: time="2026-02-13T10:53:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:31 volumio go-librespot[4912]: time="2026-02-13T10:53:31+07:00" level=debug msg="app state loaded" Feb 13 10:53:31 volumio go-librespot[4912]: time="2026-02-13T10:53:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53: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-gew4.spotify.com:80]" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+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 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+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 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=info msg="zeroconf server listening on port 42301" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="obtained new client token: AABGD+BLkbCAp1PvBk06LaDA0QlDYhuIEHkCoZFGvEta/ChyBpcbWqu6SxgrZwCE3osTDi22lTXGcSmepHBvy+DzCFjempxtU1KBGikp0j4AUbKMK5/yet3x/OmK1ZJVgxwzt3z3H/Py+nwGSzDg3jaBBKWoadNmW1qXDQ5MW+Mn9QHMYRWwNHeRba8eAeahnCpIqcNJL3jRkX+5gsY94D9PaEl14t3vMcDmyOcmDmgL0Cc9v354Utr+PQ==" Feb 13 10:53:32 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:32 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:32 volumio volumio[4651]: info: Starting Shairport Sync Feb 13 10:53:32 volumio sudo[4936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:53:32 volumio sudo[4936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:32 volumio sudo[4941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:53:32 volumio sudo[4941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:32 volumio sudo[4943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 10:53:32 volumio sudo[4943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53:32+07:00" level=debug msg="completed challenge" Feb 13 10:53:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 10:53:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 10:53:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:53:32 volumio systemd[1]: shairport-sync.service: Consumed 2.346s CPU time. Feb 13 10:53:32 volumio go-librespot[4912]: time="2026-02-13T10:53: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 13 10:53:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 10:53:32 volumio sudo[4941]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:32 volumio sudo[4936]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:33 volumio sudo[4943]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: BQBxCv4gOLzClJvMQHfirkrwUh1pYa-XsSgm-o04sf-ZxBUsl0v_tafQc3Its6UpqWsE6C9asrtFAArBh8iIus7VazYon_A4oksinjOukasqZ6_IRSz35K3b1RZx2BvWX3eyBkEnSzc5OD76cyQqVHjS-ngWjAsx5WzlbxDSmq8inNfPLyh0M2N7SLyuuQyLffgZ6NsejrLPeRiqbYj97QFIORESDUUHbwl7kVDAleGdVHt4mGOCSKaqUQxh7sNUQ-n_fl1hO1eCEuDM4rbaxkt-5XoYnGufqLkZ_-9zy2lmH1D-Mtg5CYWb Feb 13 10:53:33 volumio volumio[4651]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 10:53:33 volumio volumio[4651]: info: New Spotify access token = BQBxCv4gOLzClJvMQHfirkrwUh1pYa-XsSgm-o04sf-ZxBUsl0v_tafQc3Its6UpqWsE6C9asrtFAArBh8iIus7VazYon_A4oksinjOukasqZ6_IRSz35K3b1RZx2BvWX3eyBkEnSzc5OD76cyQqVHjS-ngWjAsx5WzlbxDSmq8inNfPLyh0M2N7SLyuuQyLffgZ6NsejrLPeRiqbYj97QFIORESDUUHbwl7kVDAleGdVHt4mGOCSKaqUQxh7sNUQ-n_fl1hO1eCEuDM4rbaxkt-5XoYnGufqLkZ_-9zy2lmH1D-Mtg5CYWb Feb 13 10:53:33 volumio volumio[4651]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 10:53:33 volumio volumio[4651]: info: Shairport-Sync Started Feb 13 10:53:33 volumio volumio[4651]: Error adding Membership: Error: addMembership EINVAL Feb 13 10:53:33 volumio volumio[4651]: info: Shairport-Sync Started Feb 13 10:53:33 volumio volumio[4651]: info: Shairport-Sync Started Feb 13 10:53:33 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:33 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:33 volumio volumio[4651]: 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 13 10:53:33 volumio volumio[4651]: info: Spotify Successfully logged in Feb 13 10:53:33 volumio volumio[4651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 10:53:33 volumio volumio[4651]: info: [1770954813568] CoreMusicLibrary::Adding element Spotify Feb 13 10:53:33 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 10:53:33 volumio volumio[4651]: Cannot find translation for source YouTube Music Feb 13 10:53:33 volumio volumio[4651]: Cannot find translation for source Spotify Feb 13 10:53:34 volumio volumio[4651]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 10:53:34 volumio volumio[4651]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 10:53:34 volumio volumio[4651]: info: VolumeController:: Volume=100 Mute =false Feb 13 10:53:34 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:34 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:34 volumio volumio[4651]: info: CoreStateMachine::pushState Feb 13 10:53:34 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:34 volumio volumio[4651]: info: CoreCommandRouter::volumioPushState Feb 13 10:53:35 volumio volumio[4651]: info: go-librespot daemon successfully initialized Feb 13 10:53:35 volumio mpd[4876]: 2026-02-13T10:53:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 10:53:35 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 10:53:35 volumio sudo[4836]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:35 volumio sudo[4847]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 10:53:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:35 volumio go-librespot[4985]: go-librespot daemon starting... Feb 13 10:53:35 volumio go-librespot[4986]: time="2026-02-13T10:53:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:35 volumio go-librespot[4986]: time="2026-02-13T10:53:35+07:00" level=debug msg="app state loaded" Feb 13 10:53:35 volumio go-librespot[4986]: time="2026-02-13T10:53:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:36 volumio volumio[4651]: error: MPD error: The expression evaluated to a falsy value: Feb 13 10:53:36 volumio volumio[4651]: assert.ok(self.idling) Feb 13 10:53:36 volumio volumio[4651]: error: The expression evaluated to a falsy value: Feb 13 10:53:36 volumio volumio[4651]: assert.ok(self.idling) Feb 13 10:53:36 volumio volumio[4651]: error: updateQueue error: null Feb 13 10:53:36 volumio volumio[4651]: info: MPD running with PID4876 Feb 13 10:53:36 volumio volumio[4651]: ,establishing connection Feb 13 10:53:36 volumio volumio[4651]: info: Completed starting Core Plugins Feb 13 10:53:36 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:36 volumio volumio[4651]: info: ----- MyVolumio plugins startup ---- Feb 13 10:53:36 volumio volumio[4651]: info: ------------------------------------------- Feb 13 10:53:36 volumio volumio[4651]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 10:53:36 volumio volumio[4651]: error: updateQueue error: null Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53: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 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53: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 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=info msg="zeroconf server listening on port 39087" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="obtained new client token: AACaQRdnYmvM9Sj2zq1LZNf4vH5rdiQQda9sho3+XZR/VAzAwTVCkYrMwKyz2Q7aRzGWj9SfpUlf2vtGXyYQsT0TmxlhzGM1hRBQoJcA6vqrODjufxd/85IQPu+q0wOVoLdOqWf8iX6sAt2syxw8YUfgi6dk6N7SVqWQC5uWQijsDNR6Vwgz1P5w+bvmoYDBzkJLnSA33Gum8l+qm4rGj9jXK9buqKVFUKuyvL3mvaXy19IsnxB36XLI7A==" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:36 volumio go-librespot[4986]: time="2026-02-13T10:53:36+07:00" level=debug msg="completed challenge" Feb 13 10:53:37 volumio go-librespot[4986]: time="2026-02-13T10:53:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:38 volumio volumio[4651]: info: Initializing connection to go-librespot Websocket Feb 13 10:53:38 volumio volumio[4651]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:53:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 10:53:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:40 volumio go-librespot[4994]: go-librespot daemon starting... Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="app state loaded" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=info msg="zeroconf server listening on port 42049" Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="obtained new client token: AABLkoubnx8zSMTyLtW+iNUVtPg9T4EPRzIP4Ar0hORtn3CkLLioYf5DwJCzjRF57TCF45DoWTtsvThftoXh0qDfab+dgBD5oNumBjXscfBjMOwkref5NbwcwEXZIH9VZEs72UdGFyzyw2deMcbzkhCl5i6ekYV+1eHdo8m3zHwEJlszErEOUJlF9Ixdz8wyg3KGT3Q7iQb9uTyIfndQUxfjz5QV/RJYMZDEr68Wogc5vsO/weTIqaNrag==" Feb 13 10:53:40 volumio volumio[4651]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 10:53:40 volumio go-librespot[4995]: time="2026-02-13T10:53:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:41 volumio go-librespot[4995]: time="2026-02-13T10:53:41+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:41 volumio go-librespot[4995]: time="2026-02-13T10:53:41+07:00" level=debug msg="completed challenge" Feb 13 10:53:41 volumio go-librespot[4995]: time="2026-02-13T10:53:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:41 volumio volumio[4651]: info: Initializing connection to go-librespot Websocket Feb 13 10:53:41 volumio volumio[4651]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 10:53:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 10:53:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:44 volumio go-librespot[5002]: go-librespot daemon starting... Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="app state loaded" Feb 13 10:53:44 volumio volumio[4651]: info: Initializing connection to go-librespot Websocket Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="new websocket client" Feb 13 10:53:44 volumio volumio[4651]: info: Connection to go-librespot Websocket established Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 10:53:44 volumio go-librespot[5003]: time="2026-02-13T10:53:44+07:00" level=info msg="zeroconf server listening on port 33773" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="obtained new client token: AAAzUC2DSDnQg4V5VmooR+lcGWL8A/s1i9AkRG+speFcBasew++q311rmHdH90xGxudmM390nIxtcHfPIGz07Cgox21TvnspFVbzJIGGqkFCjhvURmxJKltOcvoOvMUcr8rfIHYxQyehWQCpdk25tfYd5o5YjATRqCJf+WQBhX2wRf9cxwW4h1fE3bB5a86z8/dn4QS6KXUksxuvCDdENt0rI04c+GxLmgXLB6gPMJO0G2eWLbfhS0Y=" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+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 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 10:53:45 volumio volumio[4651]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=debug msg="completed challenge" Feb 13 10:53:45 volumio go-librespot[5003]: time="2026-02-13T10:53:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 10:53:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 10:53:47 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:47 volumio volumio[4651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 10:53:47 volumio volumio[4651]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 10:53:47 volumio volumio[4651]: info: MyVolumio login type: Token Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 10:53:47 volumio volumio[4651]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 10:53:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 10:53:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 10:53:48 volumio go-librespot[5024]: go-librespot daemon starting... Feb 13 10:53:48 volumio volumio[4651]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 10:53:48 volumio volumio[4651]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 10:53:48 volumio volumio[4651]: info: Streaming services startup Feb 13 10:53:48 volumio volumio[4651]: info: Starting Streaming Daemon Feb 13 10:53:48 volumio go-librespot[5025]: time="2026-02-13T10:53:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 10:53:48 volumio go-librespot[5025]: time="2026-02-13T10:53:48+07:00" level=debug msg="app state loaded" Feb 13 10:53:48 volumio go-librespot[5025]: time="2026-02-13T10:53:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 10:53:48 volumio sudo[5033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 10:53:48 volumio sudo[5033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 10:53:48 volumio volumio[4651]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 10:53:49 volumio sudo[5033]: pam_unix(sudo:session): session closed for user root Feb 13 10:53:49 volumio volumio[4651]: info: Getting Spotify volume Feb 13 10:53:49 volumio volumio[4651]: info: Connection to go-librespot Websocket closed Feb 13 10:53:49 volumio volumio[4651]: error: Cannot start Volumio Streaming Daemon Feb 13 10:53:49 volumio volumio[4651]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 10:53:49 volumio volumio[4651]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 10:53:49 volumio volumio[4651]: 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 13 10:53:49 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:49 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:49 volumio volumio[4651]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 13 10:53:49 volumio volumio[4651]: SPOTIFY: SPOTIFY VOLUME undefined Feb 13 10:53:49 volumio volumio[4651]: SPOTIFY: VOLUMIO VOLUME 100 Feb 13 10:53:49 volumio volumio[4651]: info: Aligning Spotify Volume to Volumio Volume Feb 13 10:53:49 volumio volumio[4651]: info: CoreCommandRouter::volumioGetState Feb 13 10:53:49 volumio volumio[4651]: info: CorePlayQueue::getTrack 0 Feb 13 10:53:49 volumio volumio[4651]: info: Setting Spotify Volume from Volumio: 100 Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+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 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+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 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=info msg="zeroconf server listening on port 33017" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="obtained new client token: AACVOtgNfKmUXpxUwImYu/8D9bQGeWVkKQ3tFbPcWfwp/bs3GlBTmAjW6YwOZTjpSukGawb8Mf5ZRCl0isWXPYBhcPakJaY12lNwntfi/OFoDhXNc231ED8EJF1Tjo6bSlCbFYeQuOxt770NJShC2Q5ZMk/aX55r/dIa7+LmGlqubXAYRlwGGvG90CLU42SW3hgw5hO3JM8zcNyIrMneUFP+4TNwWOV4FtHZ0b6YHHR25nI7rknOCcqXCA==" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="completed keyexchange" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53:49+07:00" level=debug msg="completed challenge" Feb 13 10:53:49 volumio go-librespot[5025]: time="2026-02-13T10:53: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 13 10:53:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 10:53:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 10:53:49 volumio volumio[4651]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:50 volumio volumio[4651]: Error: socket hang up Feb 13 10:53:50 volumio volumio[4651]: at connResetException (node:internal/errors:720:14) Feb 13 10:53:50 volumio volumio[4651]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 13 10:53:50 volumio volumio[4651]: at Socket.emit (node:events:526:35) Feb 13 10:53:50 volumio volumio[4651]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 13 10:53:50 volumio volumio[4651]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 13 10:53:50 volumio volumio[4651]: code: 'ECONNRESET', Feb 13 10:53:50 volumio volumio[4651]: response: undefined Feb 13 10:53:50 volumio volumio[4651]: } Feb 13 10:53:50 volumio volumio[4651]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 10:53:51 volumio sudo[5056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 10:52' Feb 13 10:53:51 volumio sudo[5056]: 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"