Feb 18 11:53:00 volumio volumio[27878]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 11:53:00 volumio volumio[27878]: info: Discovery: Found device Volumio Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:00 volumio volumio[27878]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:00 volumio volumio[27878]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 11:53:00 volumio volumio[27878]: info: Discovery: Found device Volumio Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:00 volumio volumio[27878]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:00 volumio volumio[27878]: info: MPD Permissions set Feb 18 11:53:00 volumio volumio[27878]: info: MPD Permissions set Feb 18 11:53:00 volumio volumio[27878]: info: Upmpdcli Daemon Started Feb 18 11:53:00 volumio volumio[27878]: info: Spotify config file written Feb 18 11:53:00 volumio volumio[27878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 11:53:00 volumio sudo[28124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 11:53:00 volumio sudo[28124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 18 11:53:00 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 18 11:53:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:00 volumio go-librespot[28134]: go-librespot daemon starting... Feb 18 11:53:00 volumio sudo[28124]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:00 volumio volumio[27878]: info: No need to fix Spotify hosts Feb 18 11:53:00 volumio go-librespot[28141]: time="2026-02-18T11:53:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:00 volumio go-librespot[28141]: time="2026-02-18T11:53:00+07:00" level=debug msg="app state loaded" Feb 18 11:53:00 volumio go-librespot[28141]: time="2026-02-18T11:53:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=info msg="zeroconf server listening on port 33535" Feb 18 11:53:01 volumio volumio[27878]: info: Volumio called home Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="obtained new client token: AADez9Li+1HyowQjAfH4UTmW9goSL6ZJQH/dDeheyltcco8Q4ucSxRHBaEk/RMPlkyjytVcoFJ8fVH7Q3E5WbsUXkg9lSJN8gSoU1dcsVNhpxwJW1tU4XksPHmXVftgn4IyBlm4uMh7OCnbRp6L7XAZ8xQ4t+NzS85P1jhBzXZ6xDUPhJeuuOI9NPAPIAgYTo34eBydAJK5bRNLKHtzl/bT7gRXea0K9hQB5OH2rAMlIC/tdyWaqX12eIQ==" Feb 18 11:53:01 volumio volumio[27878]: info: Starting Shairport Sync Feb 18 11:53:01 volumio volumio[27878]: info: Starting Shairport Sync Feb 18 11:53:01 volumio volumio[27878]: info: Starting Shairport Sync Feb 18 11:53:01 volumio sudo[28161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:53:01 volumio sudo[28161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:01 volumio sudo[28163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:53:01 volumio sudo[28163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:01 volumio sudo[28165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:53:01 volumio sudo[28165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:01 volumio go-librespot[28141]: time="2026-02-18T11:53:01+07:00" level=debug msg="completed challenge" Feb 18 11:53:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 11:53:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 11:53:02 volumio go-librespot[28141]: time="2026-02-18T11:53:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 11:53:02 volumio systemd[1]: shairport-sync.service: Consumed 2.294s CPU time. Feb 18 11:53:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 11:53:02 volumio sudo[28163]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:02 volumio sudo[28165]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:02 volumio sudo[28161]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:02 volumio volumio[27878]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 11:53:02 volumio volumio[27878]: SPOTIFY: BQCwWQgeD_oX6vlnY-s23mgk4V-vRw9ujtyO1HPaP_Tj5G-j-89O_x72OC6aRFYl7zRhSwxKHAK9vmesFRcilWKXqkZcECPz0nPBt8iCGvdKCsxq2BKzTyulIMviPB7Eb-aCXZzMACRVxOB4ODtggZZjBuQuU2VulS6UjRZL2jM6WyWWepY3zBTQIIwO430MXr2Lc8rSDBGZMczdEbJtfcLQ--7LO7Dxhxdlv554ydb-hxWLxIXdkrM0wv_NNgNl7jQXjYQi_QUIli6RtqbsRzvG824nEmUGtJ7VE9VMziUIabjq2ZrQCiF1 Feb 18 11:53:02 volumio volumio[27878]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 11:53:02 volumio volumio[27878]: info: New Spotify access token = BQCwWQgeD_oX6vlnY-s23mgk4V-vRw9ujtyO1HPaP_Tj5G-j-89O_x72OC6aRFYl7zRhSwxKHAK9vmesFRcilWKXqkZcECPz0nPBt8iCGvdKCsxq2BKzTyulIMviPB7Eb-aCXZzMACRVxOB4ODtggZZjBuQuU2VulS6UjRZL2jM6WyWWepY3zBTQIIwO430MXr2Lc8rSDBGZMczdEbJtfcLQ--7LO7Dxhxdlv554ydb-hxWLxIXdkrM0wv_NNgNl7jQXjYQi_QUIli6RtqbsRzvG824nEmUGtJ7VE9VMziUIabjq2ZrQCiF1 Feb 18 11:53:02 volumio volumio[27878]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 11:53:02 volumio volumio[27878]: info: Shairport-Sync Started Feb 18 11:53:02 volumio volumio[27878]: Error adding Membership: Error: addMembership EINVAL Feb 18 11:53:02 volumio volumio[27878]: info: Shairport-Sync Started Feb 18 11:53:02 volumio volumio[27878]: info: Shairport-Sync Started Feb 18 11:53:02 volumio volumio[27878]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:02 volumio volumio[27878]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:02 volumio volumio[27878]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 18 11:53:02 volumio volumio[27878]: info: Spotify Successfully logged in Feb 18 11:53:02 volumio volumio[27878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:53:02 volumio volumio[27878]: info: [1771390382749] CoreMusicLibrary::Adding element Spotify Feb 18 11:53:02 volumio volumio[27878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:02 volumio volumio[27878]: Cannot find translation for source YouTube Music Feb 18 11:53:02 volumio volumio[27878]: Cannot find translation for source Spotify Feb 18 11:53:02 volumio volumio[27878]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 11:53:02 volumio volumio[27878]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:53:03 volumio volumio[27878]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:53:03 volumio volumio[27878]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:03 volumio volumio[27878]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:03 volumio volumio[27878]: info: CoreStateMachine::pushState Feb 18 11:53:03 volumio volumio[27878]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:03 volumio volumio[27878]: info: CoreCommandRouter::volumioPushState Feb 18 11:53:04 volumio volumio[27878]: info: go-librespot daemon successfully initialized Feb 18 11:53:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 18 11:53:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:05 volumio go-librespot[28201]: go-librespot daemon starting... Feb 18 11:53:05 volumio go-librespot[28202]: time="2026-02-18T11:53:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:05 volumio go-librespot[28202]: time="2026-02-18T11:53:05+07:00" level=debug msg="app state loaded" Feb 18 11:53:05 volumio go-librespot[28202]: time="2026-02-18T11:53:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:05 volumio mpd[28105]: 2026-02-18T11:53:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 11:53:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 11:53:05 volumio sudo[28064]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:05 volumio sudo[28075]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:05 volumio go-librespot[28202]: time="2026-02-18T11:53:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:05 volumio go-librespot[28202]: time="2026-02-18T11:53:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:05 volumio go-librespot[28202]: time="2026-02-18T11:53:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:06 volumio volumio[27878]: error: MPD error: The expression evaluated to a falsy value: Feb 18 11:53:06 volumio volumio[27878]: assert.ok(self.idling) Feb 18 11:53:06 volumio volumio[27878]: error: The expression evaluated to a falsy value: Feb 18 11:53:06 volumio volumio[27878]: assert.ok(self.idling) Feb 18 11:53:06 volumio go-librespot[28202]: time="2026-02-18T11:53:06+07:00" level=info msg="zeroconf server listening on port 45053" Feb 18 11:53:06 volumio volumio[27878]: info: MPD running with PID28105 Feb 18 11:53:06 volumio volumio[27878]: ,establishing connection Feb 18 11:53:06 volumio volumio[27878]: error: updateQueue error: null Feb 18 11:53:06 volumio volumio[27878]: info: Completed starting Core Plugins Feb 18 11:53:06 volumio volumio[27878]: info: ------------------------------------------- Feb 18 11:53:06 volumio volumio[27878]: info: ----- MyVolumio plugins startup ---- Feb 18 11:53:06 volumio volumio[27878]: info: ------------------------------------------- Feb 18 11:53:06 volumio volumio[27878]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 11:53:06 volumio volumio[27878]: error: updateQueue error: null Feb 18 11:53:06 volumio go-librespot[28202]: time="2026-02-18T11:53:06+07:00" level=debug msg="obtained new client token: AAAkyUTdC2etu64LlTY7mzI07L/QxN94dAm35Kiq51iEjsdee2Pl//gBwJNMqKFaHpNd1Cip7lO1N7S4kxMGWJ/DAw8kB7HDWb63GW6fVdwPup+WHnUtyFn1uu0kahA588IDxcEFCVSmguq8NtqpES3oeKUGj9+I981b+vxZKd9FmhQ/O6A6pjpNQKnuPJo7vMrpBbwaBsiOlAurA85YFT8qn0/rKyrL20nqWwpkaJ2Pz4PhmovtD6c=" Feb 18 11:53:06 volumio go-librespot[28202]: time="2026-02-18T11:53:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:06 volumio go-librespot[28202]: time="2026-02-18T11:53:06+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:06 volumio go-librespot[28202]: time="2026-02-18T11:53:06+07:00" level=debug msg="completed challenge" Feb 18 11:53:06 volumio go-librespot[28202]: time="2026-02-18T11:53:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:07 volumio volumio[27878]: info: Initializing connection to go-librespot Websocket Feb 18 11:53:07 volumio volumio[27878]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:53:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 18 11:53:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:09 volumio go-librespot[28214]: go-librespot daemon starting... Feb 18 11:53:09 volumio go-librespot[28215]: time="2026-02-18T11:53:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:09 volumio go-librespot[28215]: time="2026-02-18T11:53:09+07:00" level=debug msg="app state loaded" Feb 18 11:53:09 volumio go-librespot[28215]: time="2026-02-18T11:53:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=info msg="zeroconf server listening on port 41317" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="obtained new client token: AABsrL5WFDK/pnGGH9PXom7zGStOdfc7C2iM5Sc4wxpbAtcu2C/8qJXWiYZ/7DTvJhqD8kr8XuiMq24ugHEeaW3xalYt33bktIWVD2+24O4z4hdK4BRRXAMztWxlWUU4ZvOaT96JhT3gWV3WnyrFclK/7pWwrAJ0gMURDxQDV9p6N5qcgMyCHoTqLUyr/0jhwZizOzylNbkrRV4mdRIeGeKKAKgFpt3yUhCtzyl0X2YTJtf+AfM8uv2vbw==" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="completed challenge" Feb 18 11:53:10 volumio volumio[27878]: info: Initializing connection to go-librespot Websocket Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=debug msg="new websocket client" Feb 18 11:53:10 volumio go-librespot[28215]: time="2026-02-18T11:53:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:10 volumio volumio[27878]: info: Connection to go-librespot Websocket established Feb 18 11:53:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:10 volumio volumio[27878]: info: Connection to go-librespot Websocket closed Feb 18 11:53:10 volumio volumio[27878]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 11:53:13 volumio volumio[27878]: info: Getting Spotify volume Feb 18 11:53:13 volumio volumio[27878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:53:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 18 11:53:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:13 volumio volumio[27878]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:53:13 volumio volumio[27878]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 11:53:13 volumio volumio[27878]: errno: -111, Feb 18 11:53:13 volumio volumio[27878]: code: 'ECONNREFUSED', Feb 18 11:53:13 volumio volumio[27878]: syscall: 'connect', Feb 18 11:53:13 volumio volumio[27878]: address: '127.0.0.1', Feb 18 11:53:13 volumio volumio[27878]: port: 9879, Feb 18 11:53:13 volumio volumio[27878]: response: undefined Feb 18 11:53:13 volumio volumio[27878]: } Feb 18 11:53:13 volumio volumio[27878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:53:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:13 volumio go-librespot[28237]: go-librespot daemon starting... Feb 18 11:53:13 volumio go-librespot[28241]: time="2026-02-18T11:53:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:13 volumio go-librespot[28241]: time="2026-02-18T11:53:13+07:00" level=debug msg="app state loaded" Feb 18 11:53:13 volumio go-librespot[28241]: time="2026-02-18T11:53:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=info msg="zeroconf server listening on port 41901" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="obtained new client token: AAAc57A0x7S7Yf/M1q2lrveKRCNQhs9QTcpJocglciYDBbftMLQe6Z6vLvvgNaOWZDTNbrUgGMNy2nJ9e6jbAAgxd5GEw48wWmwJcnfeuWkDnZsFS1hQk+kI5fLTiqPQO6jWaoKELWq6D/ayF4q0Wg6+A3qnoxh5RiOQdw4eLTQ+X1A7BwUiKT1DohEUX6VavMzwie7qWEDyEs8G/7ftR8kmJJJmadfFU5lc1eSJ/4QexhkZrMvUy1GJkQ==" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11:53:14+07:00" level=debug msg="completed challenge" Feb 18 11:53:14 volumio go-librespot[28241]: time="2026-02-18T11: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 18 11:53:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:15 volumio sudo[28259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 11:52' Feb 18 11:53:15 volumio sudo[28259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:15 volumio sudo[28259]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:15 volumio volumio-remote-updater[25144]: [2026-02-18 11:53:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 11:53:15 volumio volumio-remote-updater[25144]: [2026-02-18 11:53:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 11:53:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 11:53:15 volumio systemd[1]: volumio.service: Consumed 50.436s CPU time. Feb 18 11:53:15 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 11:53:15 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 11:53:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21860. Feb 18 11:53:15 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 11:53:15 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 11:53:15 volumio systemd[1]: volumio.service: Consumed 50.436s CPU time. Feb 18 11:53:15 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 11:53:15 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 11:53:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 18 11:53:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:18 volumio go-librespot[28289]: go-librespot daemon starting... Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=debug msg="app state loaded" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=info msg="zeroconf server listening on port 45637" Feb 18 11:53:18 volumio go-librespot[28291]: time="2026-02-18T11:53:18+07:00" level=debug msg="obtained new client token: AAC0hANVNhi6TXxFs9mGAzFXtYuI2pr5KWtm5BVajQ5OKwqW7Ns5iglVAiA3vzGK7vb15fLFh95SUanzn/SVBrq8Kg44pWcAy0UfwqYvVoqm7BgiJSkhh6RgMDSZezNGLp6fo7/bCo2lLnS4YI5qyNwHW57rx1C7Xl7nLk9l0YqdPs/5jPL2FmM7QBpa7gJf1pvheH3OhjhnRu940/ZQV30cd85jeCs+uPb/VX/gfhhCXT6Wp8uHCpMiKA==" Feb 18 11:53:19 volumio go-librespot[28291]: time="2026-02-18T11:53:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:19 volumio go-librespot[28291]: time="2026-02-18T11:53:19+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:19 volumio go-librespot[28291]: time="2026-02-18T11:53:19+07:00" level=debug msg="completed challenge" Feb 18 11:53:19 volumio go-librespot[28291]: time="2026-02-18T11:53:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:19 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:19 volumio volumio[28274]: info: ----- Volumio3 ---- Feb 18 11:53:19 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:19 volumio volumio[28274]: info: ----- System startup ---- Feb 18 11:53:19 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:20 volumio volumio-remote-updater[25144]: [2026-02-18 11:53:20] [connect] Successful connection Feb 18 11:53:20 volumio volumio[28274]: info: MYVOLUMIO Environment detected Feb 18 11:53:20 volumio volumio[28274]: info: Plugin folders cleanup Feb 18 11:53:20 volumio volumio[28274]: info: Scanning into folder /volumio/app/plugins/ Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category audio_interface Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category miscellanea Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category music_service Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category plugins.json Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category system_controller Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category user_interface Feb 18 11:53:20 volumio volumio[28274]: info: Scanning into folder /data/plugins/ Feb 18 11:53:20 volumio volumio[28274]: info: Scanning category music_service Feb 18 11:53:20 volumio volumio[28274]: info: Plugin folders cleanup completed Feb 18 11:53:20 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:20 volumio volumio[28274]: info: ----- Core plugins startup ---- Feb 18 11:53:20 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:21 volumio volumio[28274]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 11:53:21 volumio volumio[28274]: info: Adding plugin upnp to MyMusic Plugins Feb 18 11:53:21 volumio volumio[28274]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 11:53:21 volumio volumio[28274]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 11:53:21 volumio volumio[28274]: info: Loading plugins from folder /data/plugins/ Feb 18 11:53:21 volumio volumio[28274]: info: Loading plugin "system"... Feb 18 11:53:21 volumio volumio[28274]: info: Loading plugin "appearance"... Feb 18 11:53:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 18 11:53:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:22 volumio go-librespot[28310]: go-librespot daemon starting... Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=debug msg="app state loaded" Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:22 volumio volumio[28274]: info: Loading plugin "network"... Feb 18 11:53:22 volumio go-librespot[28311]: time="2026-02-18T11:53:22+07:00" level=info msg="zeroconf server listening on port 46389" Feb 18 11:53:23 volumio volumio[28274]: info: Refreshing Cached IP Addresses Feb 18 11:53:23 volumio sudo[28319]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 11:53:23 volumio sudo[28319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "services"... Feb 18 11:53:23 volumio sudo[28321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "alsa_controller"... Feb 18 11:53:23 volumio sudo[28321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:23 volumio sudo[28319]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:23 volumio sudo[28321]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:23 volumio sudo[28328]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 11:53:23 volumio sudo[28328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:23 volumio go-librespot[28311]: time="2026-02-18T11:53:23+07:00" level=debug msg="obtained new client token: AAAqhdXskIWYIwV8O3NR8S/gOUnJqXLXGo3r7DiQ6WvYSIWnSSWcUPcvG+c81NpO1s4qH5LFq4fH+LZgKiZDLh1nogFOu/OPAZQvYod2OkO8+PrDR2Jp/TmkTmnMFTKGYNfMSpFFdHKb6cgxnPyOe2QfDKG97eRDZv9fhcsvgyU5TrSum4Vdek1BizWE6QUEeAM9+1qoyZMSORsbSIn5JAUy4yNytseJlKC/DGFZnaeWdokngkKwGWk=" Feb 18 11:53:23 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "wizard"... Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "networkfs"... Feb 18 11:53:23 volumio go-librespot[28311]: time="2026-02-18T11:53:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:23 volumio volumio[28274]: info: Starting Udev Watcher for removable devices Feb 18 11:53:23 volumio volumio[28274]: info: Ignoring mount for partition: boot Feb 18 11:53:23 volumio volumio[28274]: info: Ignoring mount for partition: volumio Feb 18 11:53:23 volumio volumio[28274]: info: Ignoring mount for partition: volumio_data Feb 18 11:53:23 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "volumio_command_line_client"... Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "upnp"... Feb 18 11:53:23 volumio volumio[28274]: info: [1771390403417] Starting Upmpd Daemon Feb 18 11:53:23 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "my_music"... Feb 18 11:53:23 volumio volumio[28274]: info: Loading plugin "mpd"... Feb 18 11:53:23 volumio go-librespot[28311]: time="2026-02-18T11:53:23+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:23 volumio go-librespot[28311]: time="2026-02-18T11:53:23+07:00" level=debug msg="completed challenge" Feb 18 11:53:23 volumio go-librespot[28311]: time="2026-02-18T11:53:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:24 volumio volumio[28274]: info: Loading plugin "upnp_browser"... Feb 18 11:53:25 volumio sudo[28328]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 18 11:53:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:26 volumio go-librespot[28352]: go-librespot daemon starting... Feb 18 11:53:26 volumio go-librespot[28353]: time="2026-02-18T11:53:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:26 volumio go-librespot[28353]: time="2026-02-18T11:53:26+07:00" level=debug msg="app state loaded" Feb 18 11:53:26 volumio go-librespot[28353]: time="2026-02-18T11:53:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:27 volumio volumio[28274]: info: Starting UPNP Browser Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "alarm-clock"... Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "airplay_emulation"... Feb 18 11:53:27 volumio volumio[28274]: info: Starting Shairport Sync Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "last_100"... Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "webradio"... Feb 18 11:53:27 volumio go-librespot[28353]: time="2026-02-18T11:53:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 11:53:27 volumio go-librespot[28353]: time="2026-02-18T11:53:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 11:53:27 volumio go-librespot[28353]: time="2026-02-18T11:53:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 11:53:27 volumio go-librespot[28353]: time="2026-02-18T11:53:27+07:00" level=info msg="zeroconf server listening on port 40737" Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "i2s_dacs"... Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "volumiodiscovery"... Feb 18 11:53:27 volumio volumio[28274]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 11:53:27 volumio volumio[28274]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:53:27 volumio node[28274]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 11:53:27 volumio volumio[28274]: *** WARNING *** For more information see Feb 18 11:53:27 volumio volumio[28274]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 11:53:27 volumio volumio[28274]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:53:27 volumio volumio[28274]: *** WARNING *** For more information see Feb 18 11:53:27 volumio node[28274]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:53:27 volumio node[28274]: *** WARNING *** For more information see Feb 18 11:53:27 volumio node[28274]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 11:53:27 volumio node[28274]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:53:27 volumio node[28274]: *** WARNING *** For more information see Feb 18 11:53:27 volumio volumio[28274]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 11:53:27 volumio volumio[28274]: info: Discovery: Started advertising with name: Volumio Feb 18 11:53:27 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 11:53:27 volumio volumio[28274]: info: Loading plugin "spop"... Feb 18 11:53:27 volumio go-librespot[28353]: time="2026-02-18T11:53:27+07:00" level=debug msg="obtained new client token: AACTgl8U6BPKF3xG8z8mC5oONRnIM8fLrrespY7/+sxoYq11FK7F85/MkSd2MCXrUQ3iJB40OU4nVY1zfyXs7E418rJPANqbmY/GBqnv+XUazwZ0wMLVFekZbsiWWjfw6IpEihDoGk3XvBQYtcqB+vFooaX13qq27TpbXkanK+Q47cdKdgL03dsAruvhv3HVa4eB7A79HiRHYVGUNI8W6K2TKcsLF7tuMxSKvnaxefANWR1bQwAvzUoRKg==" Feb 18 11:53:27 volumio go-librespot[28353]: time="2026-02-18T11:53:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:28 volumio go-librespot[28353]: time="2026-02-18T11:53:28+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:28 volumio go-librespot[28353]: time="2026-02-18T11:53:28+07:00" level=debug msg="completed challenge" Feb 18 11:53:28 volumio go-librespot[28353]: time="2026-02-18T11:53:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:29 volumio volumio[28274]: info: Loading plugin "ytcr"... Feb 18 11:53:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 18 11:53:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:31 volumio go-librespot[28363]: go-librespot daemon starting... Feb 18 11:53:31 volumio go-librespot[28364]: time="2026-02-18T11:53:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:31 volumio go-librespot[28364]: time="2026-02-18T11:53:31+07:00" level=debug msg="app state loaded" Feb 18 11:53:31 volumio go-librespot[28364]: time="2026-02-18T11:53:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:31 volumio go-librespot[28364]: time="2026-02-18T11:53:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:31 volumio go-librespot[28364]: time="2026-02-18T11:53:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:31 volumio go-librespot[28364]: time="2026-02-18T11:53:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:32 volumio go-librespot[28364]: time="2026-02-18T11:53:32+07:00" level=info msg="zeroconf server listening on port 41779" Feb 18 11:53:32 volumio go-librespot[28364]: time="2026-02-18T11:53:32+07:00" level=debug msg="obtained new client token: AADsW9ZUxFzhPuGDg+K04lAFiE4aYg6K4lSL9sav40kVUbWavZZqK3/cEDGg7/pboeUNO11DfbmxT56F6IszrDwf64fGSq65QllyBrsZIKMi72PXmnS2WNYIE9wCVvdWYyjWn+bkW23g7vcCHAWIxwMrs/QurJILpzatTAPlqaNw874jY+RycM6LfqH///zcLxlftcbnB72YZV4iYtseis7JohdizwRrk31F3ZgAMN0p9+PR2xhrYsw=" Feb 18 11:53:32 volumio volumio[28274]: info: Loading plugin "ytmusic"... Feb 18 11:53:32 volumio go-librespot[28364]: time="2026-02-18T11:53:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:32 volumio go-librespot[28364]: time="2026-02-18T11:53:32+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:32 volumio go-librespot[28364]: time="2026-02-18T11:53:32+07:00" level=debug msg="completed challenge" Feb 18 11:53:32 volumio go-librespot[28364]: time="2026-02-18T11: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 18 11:53:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:33 volumio volumio-remote-updater[25144]: [2026-02-18 11:53:33] [connect] Successful connection Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "outputs"... Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "albumart"... Feb 18 11:53:33 volumio volumio[28274]: info: Plugin example_plugin is not enabled Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "inputs"... Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "updater_comm"... Feb 18 11:53:33 volumio volumio[28274]: info: Plugin mpdemulation is not enabled Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "rest_api"... Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "websocket"... Feb 18 11:53:33 volumio volumio[28274]: info: Starting Socket.io Server version 1.7.4 Feb 18 11:53:33 volumio volumio[28274]: info: Loading plugin "RoonBridge"... Feb 18 11:53:34 volumio volumio[28274]: info: Applying required configuration parameters for plugin RoonBridge Feb 18 11:53:34 volumio volumio[28274]: info: Loading i18n strings for locale en Feb 18 11:53:34 volumio volumio[28274]: Updating browse sources language Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::initPlayerControls Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 11:53:34 volumio volumio[28274]: Express server listening on port 3000 Feb 18 11:53:34 volumio volumio[28274]: [Metrics] WebUI: 15s 930.66ms Feb 18 11:53:34 volumio volumio[28274]: info: CoreStateMachine::resetVolumioState Feb 18 11:53:34 volumio volumio[28274]: info: CoreStateMachine::getcurrentVolume Feb 18 11:53:34 volumio volumio[28274]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:53:34 volumio volumio[28387]: Forking 3 albumart workers Feb 18 11:53:34 volumio sudo[28403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 11:53:34 volumio sudo[28403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:34 volumio sudo[28403]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:34 volumio sudo[28406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 11:53:34 volumio sudo[28406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:34 volumio sudo[28406]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:34 volumio volumio[28274]: info: Volumio Network Manager: Network status updated: 1 Feb 18 11:53:35 volumio volumio[28274]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::pushState Feb 18 11:53:35 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::volumioPushState Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::updateTrackBlock Feb 18 11:53:35 volumio volumio[28274]: info: CorePlayQueue::getTrackBlock Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:53:35 volumio volumio-remote-updater[25144]: [2026-02-18 11:53:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771390413 101 Feb 18 11:53:35 volumio volumio[28274]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:35 volumio volumio[28274]: info: Reloading queue from file Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::setRepeat null single undefined Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::pushState Feb 18 11:53:35 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::volumioPushState Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::setRandom null Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::pushState Feb 18 11:53:35 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::volumioPushState Feb 18 11:53:35 volumio volumio[28274]: info: Setting Device type: Raspberry PI Feb 18 11:53:35 volumio volumio[28274]: info: Completed loading Core Plugins Feb 18 11:53:35 volumio volumio[28274]: info: Preparing to generate the ALSA configuration file Feb 18 11:53:35 volumio volumio[28274]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:53:35 volumio volumio[28274]: info: CoreStateMachine::pushState Feb 18 11:53:35 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::volumioPushState Feb 18 11:53:35 volumio volumio[28274]: info: Asound.conf file unchanged, so no further update is needed Feb 18 11:53:35 volumio volumio[28274]: info: Output device has changed, restarting MPD Feb 18 11:53:35 volumio volumio[28274]: info: Output device has changed, restarting Shairport Sync Feb 18 11:53:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:35 volumio sudo[28447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 11:53:35 volumio sudo[28447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:35 volumio go-librespot[28454]: go-librespot daemon starting... Feb 18 11:53:35 volumio sudo[28451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 11:53:35 volumio sudo[28451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:35 volumio sudo[28449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 11:53:35 volumio sudo[28449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:35 volumio sudo[28449]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:35 volumio go-librespot[28458]: time="2026-02-18T11:53:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:35 volumio go-librespot[28458]: time="2026-02-18T11:53:35+07:00" level=debug msg="app state loaded" Feb 18 11:53:35 volumio volumio[28274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 11:53:35 volumio go-librespot[28458]: time="2026-02-18T11:53:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:35 volumio volumio[28274]: info: ___________ START PLUGINS ___________ Feb 18 11:53:35 volumio volumio[28274]: info: ControllerMpd::onStart: Initializing MPD Feb 18 11:53:35 volumio volumio[28274]: info: Creating MPD Configuration file Feb 18 11:53:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 11:53:35 volumio sudo[28447]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 11:53:35 volumio sudo[28469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 11:53:35 volumio sudo[28469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:53:35 volumio volumio[28274]: info: [1771390415951] CoreMusicLibrary::Adding element Media Servers Feb 18 11:53:35 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 11:53:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 11:53:35 volumio systemd[1]: mpd.service: Consumed 7.492s CPU time. Feb 18 11:53:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 11:53:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 11:53:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 11:53:36 volumio volumio[28274]: info: UPNP Browser: Client initialized successfully Feb 18 11:53:36 volumio sudo[28467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 11:53:36 volumio sudo[28467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:36 volumio sudo[28467]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 11:53:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 11:53:36 volumio volumio[28274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:36 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 11:53:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 11:53:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 11:53:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 11:53:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 11:53:36 volumio volumio[28274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 11:53:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:53:36 volumio volumio[28274]: info: [1771390416296] CoreMusicLibrary::Adding element Last_100 Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:53:36 volumio volumio[28274]: info: [1771390416335] CoreMusicLibrary::Adding element Webradio Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:53:36 volumio volumio[28274]: info: Initializing BBC Radios Feb 18 11:53:36 volumio go-librespot[28458]: time="2026-02-18T11:53:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 11:53:36 volumio go-librespot[28458]: time="2026-02-18T11:53:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 11:53:36 volumio go-librespot[28458]: time="2026-02-18T11:53:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 11:53:36 volumio go-librespot[28458]: time="2026-02-18T11:53:36+07:00" level=info msg="zeroconf server listening on port 42385" Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:36 volumio volumio[28274]: info: Creating Spotify config file Feb 18 11:53:36 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:36 volumio sudo[28485]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 11:53:36 volumio sudo[28485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 11:53:36 volumio go-librespot[28458]: time="2026-02-18T11:53:36+07:00" level=debug msg="obtained new client token: AADshP4VI6M2GY1kuHEGFoja6cEiSFHpFnFLXNSF0APtW9ziAIYLZ25dVjqfjVbOgWbE+EUAPd6Wb6Hd/Vv3oGhLG8GtgHyKCILqRZE3cD2VCBFQx1MKhZv1naUelqcjdgBGiM7Qv0ZA7eD9CGVfm8uw1zG3e4M4ljjX8PxJL8tiM72AEbrlMcx9hlxr83j857B/HcDU0FAkeD3mtRwFcPx9wj+3dsGszf8U87pVkoSyS5D1vHsDRsmDkw==" Feb 18 11:53:36 volumio sudo[28485]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:36 volumio go-librespot[28458]: time="2026-02-18T11:53:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:37 volumio go-librespot[28458]: time="2026-02-18T11:53:37+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:37 volumio go-librespot[28458]: time="2026-02-18T11:53:37+07:00" level=debug msg="completed challenge" Feb 18 11:53:37 volumio go-librespot[28458]: time="2026-02-18T11:53:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:37 volumio volumio[28404]: Starting albumart workers Feb 18 11:53:38 volumio volumio[28402]: Starting albumart workers Feb 18 11:53:38 volumio volumio[28274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:53:38 volumio volumio[28274]: info: [1771390418096] CoreMusicLibrary::Adding element YouTube Music Feb 18 11:53:38 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:38 volumio volumio[28274]: Cannot find translation for source YouTube Music Feb 18 11:53:38 volumio volumio[28274]: info: Volumio Calling Home Feb 18 11:53:38 volumio volumio[28407]: Starting albumart workers Feb 18 11:53:38 volumio sudo[28502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 11:53:38 volumio sudo[28502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:38 volumio sudo[28502]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:39 volumio volumio[28274]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 11:53:39 volumio volumio[28274]: info: Discovery: Found device Volumio Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:39 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:39 volumio volumio[28274]: info: MPD Permissions set Feb 18 11:53:39 volumio volumio[28274]: info: MPD Permissions set Feb 18 11:53:39 volumio volumio[28274]: info: Upmpdcli Daemon Started Feb 18 11:53:39 volumio volumio[28274]: info: Volumio called home Feb 18 11:53:39 volumio volumio[28274]: info: Spotify config file written Feb 18 11:53:39 volumio sudo[28512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 11:53:39 volumio sudo[28512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:39 volumio go-librespot[28517]: go-librespot daemon starting... Feb 18 11:53:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:39 volumio sudo[28512]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:39 volumio go-librespot[28518]: time="2026-02-18T11:53:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:39 volumio go-librespot[28518]: time="2026-02-18T11:53:39+07:00" level=debug msg="app state loaded" Feb 18 11:53:39 volumio volumio[28274]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 11:53:39 volumio volumio[28274]: info: Discovery: Found device Volumio Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:39 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:39 volumio go-librespot[28518]: time="2026-02-18T11:53:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:39 volumio volumio[28274]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:39 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:40 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:40 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:53:40 volumio volumio[28274]: info: No need to fix Spotify hosts Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=info msg="zeroconf server listening on port 44947" Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=debug msg="obtained new client token: AAAiAGP+ZgYtgDNWU0QTUo7yB0GTXp7lgwHm6EtJZ6yxnuBWfNSmrskbXLbwcsci0XalOe3MjwKD5BOQSDCpyrKARMEZ6kLWogI9Kb10Y/qCScndZFukgJev08Z+Xmsg9W5cNIiPSB90JncgyoGOpSn+nDn7S55ZUWyilCD0CQXlfr75VI6zagk5vQunAf7TEH3EPfV1I+gFtutXtwXxda9xmuVjv+wi/E5qd9qA2Ouz0YR6QEsLhAE9Tg==" Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:40 volumio volumio[28274]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 11:53:40 volumio volumio[28274]: SPOTIFY: BQCYHGJXDPONDwRcgKnKPYjJo3svi8PO13Yi1N9dPkp59hUJptRvT-JluddjR9iFNy1ZKFGscKQG6OGply_oKmN0M56crV3tJBw9h8iPmufMgP4zXiosLp-V0SPOar70DbPpKIt5vK9vUVGgv8yyGgFG11985As-X_eEmCK_6qcpEW-Cjg-64z0SeclSjItiv1bQnlYAcvLdvVl0EJPgRw9goxs5BzDXInYRUEltpd9tghNpPVWsDfBq82KDBWGRKMFP8Xddv0gQcXFlohunipGjTcTjukAKGvm307ARUE4Rzp4PcUWJIr-h Feb 18 11:53:40 volumio volumio[28274]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 11:53:40 volumio volumio[28274]: info: New Spotify access token = BQCYHGJXDPONDwRcgKnKPYjJo3svi8PO13Yi1N9dPkp59hUJptRvT-JluddjR9iFNy1ZKFGscKQG6OGply_oKmN0M56crV3tJBw9h8iPmufMgP4zXiosLp-V0SPOar70DbPpKIt5vK9vUVGgv8yyGgFG11985As-X_eEmCK_6qcpEW-Cjg-64z0SeclSjItiv1bQnlYAcvLdvVl0EJPgRw9goxs5BzDXInYRUEltpd9tghNpPVWsDfBq82KDBWGRKMFP8Xddv0gQcXFlohunipGjTcTjukAKGvm307ARUE4Rzp4PcUWJIr-h Feb 18 11:53:40 volumio volumio[28274]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:40 volumio go-librespot[28518]: time="2026-02-18T11:53:40+07:00" level=debug msg="completed challenge" Feb 18 11:53:41 volumio volumio[28274]: info: Starting Shairport Sync Feb 18 11:53:41 volumio volumio[28274]: info: Starting Shairport Sync Feb 18 11:53:41 volumio volumio[28274]: info: Starting Shairport Sync Feb 18 11:53:41 volumio go-librespot[28518]: time="2026-02-18T11:53:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:41 volumio sudo[28548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:53:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:41 volumio sudo[28548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:41 volumio sudo[28552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:53:41 volumio sudo[28552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:41 volumio sudo[28550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:53:41 volumio sudo[28550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 11:53:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 11:53:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 11:53:41 volumio systemd[1]: shairport-sync.service: Consumed 2.279s CPU time. Feb 18 11:53:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 11:53:41 volumio sudo[28552]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:41 volumio sudo[28550]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:41 volumio sudo[28548]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:41 volumio volumio[28274]: info: Shairport-Sync Started Feb 18 11:53:41 volumio volumio[28274]: Error adding Membership: Error: addMembership EINVAL Feb 18 11:53:41 volumio volumio[28274]: info: Shairport-Sync Started Feb 18 11:53:41 volumio volumio[28274]: info: Shairport-Sync Started Feb 18 11:53:41 volumio volumio[28274]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:41 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:41 volumio volumio[28274]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 18 11:53:41 volumio volumio[28274]: info: Spotify Successfully logged in Feb 18 11:53:41 volumio volumio[28274]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:53:41 volumio volumio[28274]: info: [1771390421766] CoreMusicLibrary::Adding element Spotify Feb 18 11:53:41 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:53:41 volumio volumio[28274]: Cannot find translation for source YouTube Music Feb 18 11:53:41 volumio volumio[28274]: Cannot find translation for source Spotify Feb 18 11:53:42 volumio volumio[28274]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 11:53:42 volumio volumio[28274]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:53:42 volumio volumio[28274]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:53:42 volumio volumio[28274]: info: CoreCommandRouter::volumioGetState Feb 18 11:53:42 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:42 volumio volumio[28274]: info: CoreStateMachine::pushState Feb 18 11:53:42 volumio volumio[28274]: info: CorePlayQueue::getTrack 0 Feb 18 11:53:42 volumio volumio[28274]: info: CoreCommandRouter::volumioPushState Feb 18 11:53:43 volumio volumio[28274]: info: go-librespot daemon successfully initialized Feb 18 11:53:43 volumio mpd[28500]: 2026-02-18T11:53:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 11:53:43 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 11:53:43 volumio sudo[28451]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:43 volumio sudo[28469]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:44 volumio volumio[28274]: error: MPD error: The expression evaluated to a falsy value: Feb 18 11:53:44 volumio volumio[28274]: assert.ok(self.idling) Feb 18 11:53:44 volumio volumio[28274]: error: The expression evaluated to a falsy value: Feb 18 11:53:44 volumio volumio[28274]: assert.ok(self.idling) Feb 18 11:53:44 volumio volumio[28274]: info: MPD running with PID28500 Feb 18 11:53:44 volumio volumio[28274]: ,establishing connection Feb 18 11:53:44 volumio volumio[28274]: error: updateQueue error: null Feb 18 11:53:44 volumio volumio[28274]: info: Completed starting Core Plugins Feb 18 11:53:44 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:44 volumio volumio[28274]: info: ----- MyVolumio plugins startup ---- Feb 18 11:53:44 volumio volumio[28274]: info: ------------------------------------------- Feb 18 11:53:44 volumio volumio[28274]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 11:53:44 volumio volumio[28274]: error: updateQueue error: null Feb 18 11:53:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 18 11:53:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:44 volumio go-librespot[28595]: go-librespot daemon starting... Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11:53:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11:53:44+07:00" level=debug msg="app state loaded" Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11:53:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11:53:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11:53:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:44 volumio go-librespot[28596]: time="2026-02-18T11:53:44+07:00" level=info msg="zeroconf server listening on port 40663" Feb 18 11:53:45 volumio go-librespot[28596]: time="2026-02-18T11:53:45+07:00" level=debug msg="obtained new client token: AADfw6l/vaPiF91b6gpJmJsY5Pymq4408HFTYd4nXEuGzqSaIkvLEZB4FlAc/pnBRkGzFuwpHj3uEenewMH530orZGla3CNQWYbC/KwmdiS8zHVdB+Ax/lOr8s4rgh3E1uoO6qu5D0voKFCuwWos9Fgzg5SuPi2Is7alWk0SyCdMdBh3jGJrpqhzSsjuQ7zsSgeBjcEw2M2uQUbNLU54cIyB7T2h7GP2dGCg3uYPzZxEWd7Ce2+FynU=" Feb 18 11:53:45 volumio go-librespot[28596]: time="2026-02-18T11: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 18 11:53:45 volumio go-librespot[28596]: time="2026-02-18T11:53:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 11:53:45 volumio go-librespot[28596]: time="2026-02-18T11:53:45+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:45 volumio go-librespot[28596]: time="2026-02-18T11:53:45+07:00" level=debug msg="completed challenge" Feb 18 11:53:45 volumio go-librespot[28596]: time="2026-02-18T11: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 18 11:53:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:46 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:53:46 volumio volumio[28274]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:53:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 18 11:53:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:48 volumio go-librespot[28603]: go-librespot daemon starting... Feb 18 11:53:48 volumio go-librespot[28604]: time="2026-02-18T11:53:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:48 volumio go-librespot[28604]: time="2026-02-18T11:53:48+07:00" level=debug msg="app state loaded" Feb 18 11:53:48 volumio go-librespot[28604]: time="2026-02-18T11:53:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:48 volumio volumio[28274]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=info msg="zeroconf server listening on port 44139" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=debug msg="obtained new client token: AAApPfkPoR9G7GMZT2uG0osMsM3/L+5Z3nskUrUNDRfdWYBvJTMmK5I+q+crdKhRcDvMMSGEjjBvoZprPfXkHyzXjK/9SfTR8VSpNKUlkxLbXONDj0ZfUE14TBIhmlbFYsBsJ2lr4XRaBubT8svtbresvZdANPY9pqNNMfANpZhScFptzMYu0godVYHSchIN85tVP9rxyJhDTZkmyly6s23+nYwxtt38bZW6MleDuoZaxk3jLbnBCsGZYA==" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11:53:49+07:00" level=debug msg="completed challenge" Feb 18 11:53:49 volumio go-librespot[28604]: time="2026-02-18T11: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 18 11:53:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:49 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:53:49 volumio volumio[28274]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:53:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 18 11:53:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:52 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:53:52 volumio volumio[28274]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:53:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:52 volumio go-librespot[28613]: go-librespot daemon starting... Feb 18 11:53:52 volumio go-librespot[28614]: time="2026-02-18T11:53:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:52 volumio go-librespot[28614]: time="2026-02-18T11:53:52+07:00" level=debug msg="app state loaded" Feb 18 11:53:52 volumio go-librespot[28614]: time="2026-02-18T11:53:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 11:53:53 volumio volumio[28274]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=info msg="zeroconf server listening on port 46181" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="obtained new client token: AADWOXzZv1Q9JEa0CIrCoKibEg/w7j0XpQM8dAnhoed6KRu3XNK3OEhBz/Uhj3M0ITfNcjcJn/x53JqBCfkyYEvt9htZyS6uLnSkfXPHGgpL29ozo9xpQ0QwsDJ6IXOpPrD5rgFDHEV1d5fLHKKqdsizMc8S06yGHvDzekkqil3zKeXShIewfs0r5EgInHcgYyCriCgSstQO2QQneSG7P1Mj5BcnXx1u7ZkWp/u06ykmsxxEwyiC/UOIdw==" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:53 volumio go-librespot[28614]: time="2026-02-18T11:53:53+07:00" level=debug msg="completed challenge" Feb 18 11:53:54 volumio go-librespot[28614]: time="2026-02-18T11:53:54+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:55 volumio volumio[28274]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 11:53:55 volumio volumio[28274]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 11:53:55 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:55 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:53:55 volumio volumio[28274]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 11:53:55 volumio volumio[28274]: info: MyVolumio login type: Token Feb 18 11:53:55 volumio volumio[28274]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 11:53:55 volumio volumio[28274]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 11:53:56 volumio volumio[28274]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 11:53:57 volumio volumio[28274]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 11:53:57 volumio volumio[28274]: info: Streaming services startup Feb 18 11:53:57 volumio volumio[28274]: info: Starting Streaming Daemon Feb 18 11:53:57 volumio sudo[28637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 11:53:57 volumio volumio[28274]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 11:53:57 volumio sudo[28637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 18 11:53:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:57 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:53:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:53:57 volumio volumio[28274]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:53:57 volumio go-librespot[28643]: go-librespot daemon starting... Feb 18 11:53:57 volumio sudo[28637]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:57 volumio volumio[28274]: error: Cannot start Volumio Streaming Daemon Feb 18 11:53:57 volumio volumio[28274]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 11:53:57 volumio volumio[28274]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=debug msg="app state loaded" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=info msg="zeroconf server listening on port 42949" Feb 18 11:53:57 volumio go-librespot[28644]: time="2026-02-18T11:53:57+07:00" level=debug msg="obtained new client token: AAC744050XK/6OQuC7sl0U0V6MxLIkRLYQbxUSfmgADrZiWWcQZ95kiAcytRoL7+dcVxY3CAuX1feBR9mphP5910R+LlGg5RK4WXvY5/5MwCzp2Ut+Yz4Xf0O6r2pCXkBzYLhldNUakV98bupruXhV2wqbHPVbhJCWLGp6aqh7xUyfbBsCzDfYuSLWtBGNQZl3CELRCPTODMq5nmOqB23kEc5OhlSJJsro/mg2zyN9Bo5zhpFMhdnEgbrg==" Feb 18 11:53:58 volumio go-librespot[28644]: time="2026-02-18T11:53:58+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:53:58 volumio volumio[28274]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 18 11:53:58 volumio go-librespot[28644]: time="2026-02-18T11:53:58+07:00" level=debug msg="completed keyexchange" Feb 18 11:53:58 volumio go-librespot[28644]: time="2026-02-18T11:53:58+07:00" level=debug msg="completed challenge" Feb 18 11:53:58 volumio go-librespot[28644]: time="2026-02-18T11:53:58+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:53:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:53:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:53:59 volumio volumio[28274]: info: MyVolumio token set successfully Feb 18 11:53:59 volumio volumio[28274]: info: MYVOLUMIO: Adding device Feb 18 11:53:59 volumio volumio[28274]: info: MYVOLUMIO: Evaluating Server Feb 18 11:53:59 volumio volumio[28274]: info: MyVolumio status changed Feb 18 11:53:59 volumio volumio[28274]: info: Streaming services startup Feb 18 11:53:59 volumio volumio[28274]: info: Starting Streaming Daemon Feb 18 11:53:59 volumio volumio[28274]: info: Removing browser output: myVolumio user plan is not superstar Feb 18 11:53:59 volumio volumio[28274]: info: Removing audio output: Feb 18 11:53:59 volumio volumio[28274]: info: Stoppping Tunnel 1 Feb 18 11:53:59 volumio sudo[28672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 11:53:59 volumio sudo[28672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:59 volumio sudo[28672]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:59 volumio sudo[28675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 18 11:53:59 volumio volumio[28274]: error: Cannot start Volumio Streaming Daemon Feb 18 11:53:59 volumio sudo[28675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:53:59 volumio volumio[28274]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 11:53:59 volumio volumio[28274]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 11:53:59 volumio sudo[28675]: pam_unix(sudo:session): session closed for user root Feb 18 11:53:59 volumio volumio[28274]: info: Remote SSH Stopped Feb 18 11:54:00 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:54:00 volumio volumio[28274]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:54:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 18 11:54:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:01 volumio go-librespot[28678]: go-librespot daemon starting... Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=debug msg="app state loaded" Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:01 volumio volumio[28274]: info: Setting Geolocation for MyVolumio to as1 Feb 18 11:54:01 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:01 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:01 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:01 volumio go-librespot[28679]: time="2026-02-18T11:54:01+07:00" level=info msg="zeroconf server listening on port 38103" Feb 18 11:54:02 volumio go-librespot[28679]: time="2026-02-18T11:54:02+07:00" level=debug msg="obtained new client token: AAB2aNdev9hJHzb6lxjCNLQAxPY+ztxqY0CXNcDQXOBDYBNf54IbvyYbwts6d+GFQqXT3eiMExob/bhjgnMSX76XO9aTEm8wbCUfRVtNJhW4ndFBKYxDpfUHDfnBhNvqhHf6AM1+p/mRE0CLkUtU3IugLD40maLJg0O/Pvxd5spYgMKq37wL7UPR85sbYvDL+7LzXlWrJD55Kh0S1mzsU1OR6i6BjdV30x0MdPC8yRwgrDPL1Q4ZIsQ=" Feb 18 11:54:02 volumio go-librespot[28679]: time="2026-02-18T11:54:02+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 11:54:02 volumio go-librespot[28679]: time="2026-02-18T11:54:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 11:54:02 volumio volumio[28274]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 18 11:54:02 volumio go-librespot[28679]: time="2026-02-18T11:54:02+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:02 volumio go-librespot[28679]: time="2026-02-18T11:54:02+07:00" level=debug msg="completed challenge" Feb 18 11:54:02 volumio go-librespot[28679]: time="2026-02-18T11:54:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:02 volumio volumio[28274]: info: Updating MyVolumio device info Feb 18 11:54:02 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:02 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:02 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:03 volumio volumio[28274]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 18 11:54:03 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:54:03 volumio volumio[28274]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:54:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 18 11:54:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:05 volumio go-librespot[28701]: go-librespot daemon starting... Feb 18 11:54:05 volumio go-librespot[28702]: time="2026-02-18T11:54:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:05 volumio go-librespot[28702]: time="2026-02-18T11:54:05+07:00" level=debug msg="app state loaded" Feb 18 11:54:05 volumio go-librespot[28702]: time="2026-02-18T11:54:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=info msg="zeroconf server listening on port 35573" Feb 18 11:54:06 volumio volumio[28274]: info: Initializing connection to go-librespot Websocket Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="new websocket client" Feb 18 11:54:06 volumio volumio[28274]: info: Connection to go-librespot Websocket established Feb 18 11:54:06 volumio volumio[28274]: info: MYVOLUMIO: Adding device Feb 18 11:54:06 volumio volumio[28274]: info: MYVOLUMIO: Evaluating Server Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="obtained new client token: AAByTCW57kA9bru15SHcjwQExH+lPcrOtqtcldU/SEIV+vYvn8h5+sRLrB/SVBx7lebktr7+S9w8Tp+s8kaLT7/97eTt/dLTJFq57Trz12tTbMeshgS2vC9bZBoAjrxEp5wnhD9V9txTiWX2lY8oePQ7t/4lwEi4ypf1hAUCE9WG4VroSDEu5rxiuBSyBennuUCh3hO7etyQ2gfiRUWy2Hyy3AqCOKU73yEaPfgEhb94pWMZi9ra7cmT0w==" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=debug msg="completed challenge" Feb 18 11:54:06 volumio go-librespot[28702]: time="2026-02-18T11:54:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:06 volumio volumio[28274]: info: Connection to go-librespot Websocket closed Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 11:54:08 volumio volumio-remote-updater[25144]: Test mode disabled Feb 18 11:54:08 volumio volumio-remote-updater[25144]: Alpha mode disabled Feb 18 11:54:08 volumio volumio-remote-updater[25144]: Alpha legacy test mode disabled Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 18 11:54:08 volumio volumio[28274]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix for CPU utilization spike
    • \n
  • Fix for triggerhappy input permission
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add BLE onboarding plugin
    • \n
  • Enable vc4-kms-v3d for all compatible RPi models
    • \n
  • Update Tidal SDK
    • \n
\n","title":"Update v4.096","updateavailable":true} Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 18 11:54:08 volumio volumio[28274]: info: Setting Geolocation for MyVolumio to as1 Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:08 volumio volumio[28274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:09 volumio volumio[28274]: info: Getting Spotify volume Feb 18 11:54:09 volumio volumio[28274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:54:09 volumio volumio[28274]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:54:09 volumio volumio[28274]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 11:54:09 volumio volumio[28274]: errno: -111, Feb 18 11:54:09 volumio volumio[28274]: code: 'ECONNREFUSED', Feb 18 11:54:09 volumio volumio[28274]: syscall: 'connect', Feb 18 11:54:09 volumio volumio[28274]: address: '127.0.0.1', Feb 18 11:54:09 volumio volumio[28274]: port: 9879, Feb 18 11:54:09 volumio volumio[28274]: response: undefined Feb 18 11:54:09 volumio volumio[28274]: } Feb 18 11:54:09 volumio volumio[28274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:54:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 18 11:54:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:09 volumio go-librespot[28743]: go-librespot daemon starting... Feb 18 11:54:09 volumio go-librespot[28744]: time="2026-02-18T11:54:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:09 volumio go-librespot[28744]: time="2026-02-18T11:54:09+07:00" level=debug msg="app state loaded" Feb 18 11:54:09 volumio go-librespot[28744]: time="2026-02-18T11:54:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=info msg="zeroconf server listening on port 42743" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="obtained new client token: AABI1KsjZiZft27CVvF+gS+GeiQT/E0yVDTusYm9jxAlpQS6aWts7fwSaO63CMrx/StAQih9TZw3qBDkdjcEtt/9aiY6SvNyJ3saGwn4Md++yXsQ+zmTYm/7rAE4MEQRPePaJavbqUpzcL3DR1xLgOsbeqx91yVxoF2/TiDuxX4fKw3ub7p/OzVBmys0UcZ0r+0hS462teQssLW5+wAGtwIgfaD/d8uftYCr/4L8Sijao/DPMovp5lg2iw==" Feb 18 11:54:10 volumio sudo[28756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 11:53' Feb 18 11:54:10 volumio sudo[28756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:10 volumio go-librespot[28744]: time="2026-02-18T11:54:10+07:00" level=debug msg="completed challenge" Feb 18 11:54:11 volumio go-librespot[28744]: time="2026-02-18T11:54:11+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:11 volumio sudo[28756]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:11 volumio volumio-remote-updater[25144]: [2026-02-18 11:54:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 11:54:11 volumio volumio-remote-updater[25144]: [2026-02-18 11:54:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 11:54:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 11:54:11 volumio systemd[1]: volumio.service: Consumed 57.916s CPU time. Feb 18 11:54:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 11:54:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 11:54:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21861. Feb 18 11:54:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 11:54:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 11:54:11 volumio systemd[1]: volumio.service: Consumed 57.916s CPU time. Feb 18 11:54:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 11:54:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 11:54:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 18 11:54:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:14 volumio go-librespot[28786]: go-librespot daemon starting... Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=debug msg="app state loaded" Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 11:54:14 volumio go-librespot[28787]: time="2026-02-18T11:54:14+07:00" level=info msg="zeroconf server listening on port 42617" Feb 18 11:54:15 volumio go-librespot[28787]: time="2026-02-18T11:54:15+07:00" level=debug msg="obtained new client token: AAAZI8i7tgrb0AcmqgnR6QtYlTPyy2DY0mTe7Ax8ULOo0ePAkEN/+ALramyfihOT2EDCTAHi2Na34hyJMNHYcr8gOENY0PQhbbOejNdL0txls0IvdB+4Gfbhl/uj5S9BymEbUFnFNJPKCJcuM2S3CSIGyD1LDeee0+pNJ99bjtzPkr8WRK51bExiMs9mFM0+aGhWQ+MO0WaRZfW8Olxk8XTFfjPINfC1TZgiTEioAQPa3461FV2QWVA=" Feb 18 11:54:15 volumio go-librespot[28787]: time="2026-02-18T11:54:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:15 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:15 volumio volumio[28769]: info: ----- Volumio3 ---- Feb 18 11:54:15 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:15 volumio volumio[28769]: info: ----- System startup ---- Feb 18 11:54:15 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:15 volumio go-librespot[28787]: time="2026-02-18T11:54:15+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:15 volumio go-librespot[28787]: time="2026-02-18T11:54:15+07:00" level=debug msg="completed challenge" Feb 18 11:54:15 volumio go-librespot[28787]: time="2026-02-18T11:54:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:16 volumio volumio-remote-updater[25144]: [2026-02-18 11:54:16] [connect] Successful connection Feb 18 11:54:16 volumio volumio[28769]: info: MYVOLUMIO Environment detected Feb 18 11:54:16 volumio volumio[28769]: info: Plugin folders cleanup Feb 18 11:54:16 volumio volumio[28769]: info: Scanning into folder /volumio/app/plugins/ Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category audio_interface Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category miscellanea Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category music_service Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category plugins.json Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category system_controller Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category user_interface Feb 18 11:54:16 volumio volumio[28769]: info: Scanning into folder /data/plugins/ Feb 18 11:54:16 volumio volumio[28769]: info: Scanning category music_service Feb 18 11:54:16 volumio volumio[28769]: info: Plugin folders cleanup completed Feb 18 11:54:16 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:16 volumio volumio[28769]: info: ----- Core plugins startup ---- Feb 18 11:54:16 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:16 volumio volumio[28769]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 11:54:16 volumio volumio[28769]: info: Adding plugin upnp to MyMusic Plugins Feb 18 11:54:16 volumio volumio[28769]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 11:54:16 volumio volumio[28769]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 11:54:16 volumio volumio[28769]: info: Loading plugins from folder /data/plugins/ Feb 18 11:54:16 volumio volumio[28769]: info: Loading plugin "system"... Feb 18 11:54:16 volumio volumio[28769]: info: Loading plugin "appearance"... Feb 18 11:54:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 18 11:54:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:18 volumio go-librespot[28806]: go-librespot daemon starting... Feb 18 11:54:18 volumio go-librespot[28807]: time="2026-02-18T11:54:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:18 volumio go-librespot[28807]: time="2026-02-18T11:54:18+07:00" level=debug msg="app state loaded" Feb 18 11:54:18 volumio go-librespot[28807]: time="2026-02-18T11:54:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:18 volumio volumio[28769]: info: Loading plugin "network"... Feb 18 11:54:18 volumio volumio[28769]: info: Refreshing Cached IP Addresses Feb 18 11:54:18 volumio sudo[28815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 11:54:18 volumio sudo[28815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:18 volumio sudo[28817]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 11:54:18 volumio sudo[28817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:18 volumio sudo[28817]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:18 volumio sudo[28815]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "services"... Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "alsa_controller"... Feb 18 11:54:19 volumio sudo[28824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 11:54:19 volumio sudo[28824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:19 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "wizard"... Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "networkfs"... Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:19 volumio volumio[28769]: info: Starting Udev Watcher for removable devices Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=info msg="zeroconf server listening on port 36387" Feb 18 11:54:19 volumio volumio[28769]: info: Ignoring mount for partition: boot Feb 18 11:54:19 volumio volumio[28769]: info: Ignoring mount for partition: volumio Feb 18 11:54:19 volumio volumio[28769]: info: Ignoring mount for partition: volumio_data Feb 18 11:54:19 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "volumio_command_line_client"... Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "upnp"... Feb 18 11:54:19 volumio volumio[28769]: info: [1771390459336] Starting Upmpd Daemon Feb 18 11:54:19 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "my_music"... Feb 18 11:54:19 volumio volumio[28769]: info: Loading plugin "mpd"... Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="obtained new client token: AACv9L3HvPgz6iTEy5qRTWYqtBNuxMwm/xPZej8p/WA0zCakDOEVWbG2WAuPR74o4G0gMRdoSmtelwbZc9mZYK09Tn7QtzOGIHJlQN5jg30eXzHrlQtmIoUk8VJz4DJCM4ZBJDx9yoANiofJ13XNOZPedGbthIuWzO1NJVDJf9e+k/xy8fxVKopkqHhGXX55H8zxFQ8pGLfnjtAUDB+NRgdMZESALGjrRhkaW0N7Vkf3WgrT+rXEuHkm9A==" Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=debug msg="completed challenge" Feb 18 11:54:19 volumio go-librespot[28807]: time="2026-02-18T11:54:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:20 volumio volumio[28769]: info: Loading plugin "upnp_browser"... Feb 18 11:54:21 volumio sudo[28824]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Feb 18 11:54:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:22 volumio go-librespot[28848]: go-librespot daemon starting... Feb 18 11:54:22 volumio go-librespot[28849]: time="2026-02-18T11:54:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:22 volumio go-librespot[28849]: time="2026-02-18T11:54:22+07:00" level=debug msg="app state loaded" Feb 18 11:54:22 volumio go-librespot[28849]: time="2026-02-18T11:54:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:22 volumio volumio[28769]: info: Starting UPNP Browser Feb 18 11:54:22 volumio volumio[28769]: info: Loading plugin "alarm-clock"... Feb 18 11:54:23 volumio volumio[28769]: info: Loading plugin "airplay_emulation"... Feb 18 11:54:23 volumio volumio[28769]: info: Starting Shairport Sync Feb 18 11:54:23 volumio volumio[28769]: info: Loading plugin "last_100"... Feb 18 11:54:23 volumio volumio[28769]: info: Loading plugin "webradio"... Feb 18 11:54:23 volumio volumio[28769]: info: Loading plugin "i2s_dacs"... Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:23 volumio volumio[28769]: info: Loading plugin "volumiodiscovery"... Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=info msg="zeroconf server listening on port 46773" Feb 18 11:54:23 volumio volumio[28769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 11:54:23 volumio volumio[28769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:54:23 volumio volumio[28769]: *** WARNING *** For more information see Feb 18 11:54:23 volumio volumio[28769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 11:54:23 volumio volumio[28769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:54:23 volumio volumio[28769]: *** WARNING *** For more information see Feb 18 11:54:23 volumio node[28769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 11:54:23 volumio node[28769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:54:23 volumio node[28769]: *** WARNING *** For more information see Feb 18 11:54:23 volumio node[28769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 11:54:23 volumio node[28769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 11:54:23 volumio node[28769]: *** WARNING *** For more information see Feb 18 11:54:23 volumio volumio[28769]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 11:54:23 volumio volumio[28769]: info: Discovery: Started advertising with name: Volumio Feb 18 11:54:23 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 11:54:23 volumio volumio[28769]: info: Loading plugin "spop"... Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="obtained new client token: AABVGuv4A/+cRcMYvI69fzt9qVz0f7gHFaPyADEfRe7uXkXRGVfVbUOQXIQNWPnK1UT+xMk7ndllXhrle4aAobSBhSl7dBT89YvxIZjWqLx/UvssSJ1kxo+PvopXVt+e13eXDyN22ORHqYiu425U31QykEygVvyfPSRWPQQBJaJ1ZkJZdtn2E3eCPhjVFlEStm59oWN6Fiqrp3SGtIe5NJnhb0k6sKJbPx+jlkcKeA9GUj0jTbu+VS+S9w==" Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:23 volumio go-librespot[28849]: time="2026-02-18T11:54:23+07:00" level=debug msg="completed challenge" Feb 18 11:54:24 volumio go-librespot[28849]: time="2026-02-18T11:54:24+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:25 volumio volumio[28769]: info: Loading plugin "ytcr"... Feb 18 11:54:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Feb 18 11:54:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:27 volumio go-librespot[28860]: go-librespot daemon starting... Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=debug msg="app state loaded" Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:27 volumio go-librespot[28861]: time="2026-02-18T11:54:27+07:00" level=info msg="zeroconf server listening on port 44545" Feb 18 11:54:28 volumio go-librespot[28861]: time="2026-02-18T11:54:28+07:00" level=debug msg="obtained new client token: AAAozjGMPAIdGh5nGSXkr3DDFIuPGEBOdTAu3zQeoEx+/7AybUvu6cVYl8DfoWq4kLFYm93sjKgmAKmEA6nGXyJvBHu9/PGeAL6cAMFV8uMhF/p/+CMyXi/bxAOPPTckSOSjsvSfUHxpiBhMgMrK/VMPICl52LiYPWkCbehtc2yNoPbI5dEv4Q6HEm0R5srGb/mypPTY6Zrp58SSDC+WNh1rSZAEO7w5P2ON8ESZg5Rmt5Vl0Ft97FM=" Feb 18 11:54:28 volumio volumio[28769]: info: Loading plugin "ytmusic"... Feb 18 11:54:28 volumio go-librespot[28861]: time="2026-02-18T11:54:28+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 18 11:54:28 volumio go-librespot[28861]: time="2026-02-18T11:54:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 11:54:28 volumio go-librespot[28861]: time="2026-02-18T11:54:28+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:28 volumio go-librespot[28861]: time="2026-02-18T11:54:28+07:00" level=debug msg="completed challenge" Feb 18 11:54:28 volumio go-librespot[28861]: time="2026-02-18T11:54:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:28 volumio volumio-remote-updater[25144]: [2026-02-18 11:54:28] [connect] Successful connection Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "outputs"... Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "albumart"... Feb 18 11:54:29 volumio volumio[28769]: info: Plugin example_plugin is not enabled Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "inputs"... Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "updater_comm"... Feb 18 11:54:29 volumio volumio[28769]: info: Plugin mpdemulation is not enabled Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "rest_api"... Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "websocket"... Feb 18 11:54:29 volumio volumio[28769]: info: Starting Socket.io Server version 1.7.4 Feb 18 11:54:29 volumio volumio[28769]: info: Loading plugin "RoonBridge"... Feb 18 11:54:30 volumio volumio[28769]: info: Applying required configuration parameters for plugin RoonBridge Feb 18 11:54:30 volumio volumio[28769]: info: Loading i18n strings for locale en Feb 18 11:54:30 volumio volumio[28769]: Updating browse sources language Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:54:30 volumio volumio[28882]: Forking 3 albumart workers Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::initPlayerControls Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 11:54:30 volumio volumio[28769]: Express server listening on port 3000 Feb 18 11:54:30 volumio volumio[28769]: [Metrics] WebUI: 16s 179.10ms Feb 18 11:54:30 volumio volumio[28769]: info: CoreStateMachine::resetVolumioState Feb 18 11:54:30 volumio volumio[28769]: info: CoreStateMachine::getcurrentVolume Feb 18 11:54:30 volumio volumio[28769]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:54:30 volumio sudo[28927]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 11:54:30 volumio sudo[28927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:30 volumio volumio[28769]: info: Volumio Network Manager: Network status updated: 1 Feb 18 11:54:30 volumio sudo[28929]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 11:54:30 volumio sudo[28927]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:30 volumio sudo[28929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:30 volumio sudo[28929]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:31 volumio volumio[28769]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::pushState Feb 18 11:54:31 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::volumioPushState Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::updateTrackBlock Feb 18 11:54:31 volumio volumio[28769]: info: CorePlayQueue::getTrackBlock Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:54:31 volumio volumio-remote-updater[25144]: [2026-02-18 11:54:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771390468 101 Feb 18 11:54:31 volumio volumio[28769]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:31 volumio volumio[28769]: info: Reloading queue from file Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::setRepeat null single undefined Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::pushState Feb 18 11:54:31 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::volumioPushState Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::setRandom null Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::pushState Feb 18 11:54:31 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::volumioPushState Feb 18 11:54:31 volumio volumio[28769]: info: Setting Device type: Raspberry PI Feb 18 11:54:31 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 18 11:54:31 volumio volumio[28769]: info: Completed loading Core Plugins Feb 18 11:54:31 volumio volumio[28769]: info: Preparing to generate the ALSA configuration file Feb 18 11:54:31 volumio volumio[28769]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:54:31 volumio volumio[28769]: info: CoreStateMachine::pushState Feb 18 11:54:31 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::volumioPushState Feb 18 11:54:31 volumio volumio[28769]: info: Asound.conf file unchanged, so no further update is needed Feb 18 11:54:31 volumio volumio[28769]: info: Output device has changed, restarting MPD Feb 18 11:54:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 18 11:54:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:31 volumio volumio[28769]: info: Output device has changed, restarting Shairport Sync Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:31 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:31 volumio go-librespot[28959]: go-librespot daemon starting... Feb 18 11:54:31 volumio sudo[28955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 11:54:31 volumio sudo[28955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:31 volumio sudo[28957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 11:54:31 volumio sudo[28957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:31 volumio go-librespot[28965]: time="2026-02-18T11:54:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:31 volumio go-librespot[28965]: time="2026-02-18T11:54:31+07:00" level=debug msg="app state loaded" Feb 18 11:54:31 volumio sudo[28960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 11:54:31 volumio sudo[28960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:31 volumio go-librespot[28965]: time="2026-02-18T11:54:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:31 volumio sudo[28957]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:31 volumio volumio[28769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 11:54:31 volumio volumio[28769]: info: ___________ START PLUGINS ___________ Feb 18 11:54:31 volumio volumio[28769]: info: ControllerMpd::onStart: Initializing MPD Feb 18 11:54:31 volumio volumio[28769]: info: Creating MPD Configuration file Feb 18 11:54:32 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 11:54:32 volumio sudo[28955]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:54:32 volumio volumio[28769]: info: [1771390472140] CoreMusicLibrary::Adding element Media Servers Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:54:32 volumio sudo[28980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 11:54:32 volumio sudo[28980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:32 volumio sudo[28978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 11:54:32 volumio sudo[28978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:32 volumio volumio[28769]: info: UPNP Browser: Client initialized successfully Feb 18 11:54:32 volumio sudo[28978]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:32 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 11:54:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 11:54:32 volumio systemd[1]: mpd.service: Consumed 7.177s CPU time. Feb 18 11:54:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 11:54:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 11:54:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 11:54:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 11:54:32 volumio go-librespot[28965]: time="2026-02-18T11:54:32+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:32 volumio go-librespot[28965]: time="2026-02-18T11:54:32+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:32 volumio go-librespot[28965]: time="2026-02-18T11:54:32+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:32 volumio volumio[28769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:32 volumio go-librespot[28965]: time="2026-02-18T11:54:32+07:00" level=info msg="zeroconf server listening on port 46079" Feb 18 11:54:32 volumio volumio[28769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:54:32 volumio volumio[28769]: info: [1771390472803] CoreMusicLibrary::Adding element Last_100 Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:54:32 volumio volumio[28769]: info: [1771390472820] CoreMusicLibrary::Adding element Webradio Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:54:32 volumio go-librespot[28965]: time="2026-02-18T11:54:32+07:00" level=debug msg="obtained new client token: AACadD7NK07IHOl8lafEcPrAGDqstYRVrE5uuzPSX7ONc/aPcNCuhb/80V6NZ+PovZ5XJu7N2gn3FTAWhGiBQvz0jScK/oYsZkKgRfhUaK7bebgxU6lpbzMrYh/j6YdYJowMj4dxr2DzSi4kGM2I/pgHo0guaGVqvvQMeGecli8T21eIU3eL93xjYLcY5IPqKlCFXL3ajNX03fDTo+4/4W1xCP0aVw0Y+lMXanAESn14uCwF1T5lRogJXA==" Feb 18 11:54:32 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:54:32 volumio volumio[28769]: info: Initializing BBC Radios Feb 18 11:54:32 volumio go-librespot[28965]: time="2026-02-18T11:54:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:32 volumio sudo[28987]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 11:54:32 volumio sudo[28987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 11:54:33 volumio sudo[28987]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:33 volumio go-librespot[28965]: time="2026-02-18T11:54:33+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:33 volumio go-librespot[28965]: time="2026-02-18T11:54:33+07:00" level=debug msg="completed challenge" Feb 18 11:54:33 volumio go-librespot[28965]: time="2026-02-18T11:54:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:33 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 11:54:33 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:33 volumio volumio[28769]: info: Creating Spotify config file Feb 18 11:54:33 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:34 volumio volumio[28892]: Starting albumart workers Feb 18 11:54:34 volumio volumio[28894]: Starting albumart workers Feb 18 11:54:34 volumio volumio[28769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:54:34 volumio volumio[28769]: info: [1771390474693] CoreMusicLibrary::Adding element YouTube Music Feb 18 11:54:34 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:54:34 volumio volumio[28769]: Cannot find translation for source YouTube Music Feb 18 11:54:34 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 18 11:54:34 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 18 11:54:34 volumio systemd[1]: setdatetime-helper.service: Consumed 2.303s CPU time. Feb 18 11:54:34 volumio volumio[28769]: info: Volumio Calling Home Feb 18 11:54:34 volumio volumio[28893]: Starting albumart workers Feb 18 11:54:34 volumio sudo[29022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 11:54:34 volumio sudo[29022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:34 volumio sudo[29022]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:35 volumio volumio[28769]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 11:54:35 volumio volumio[28769]: info: Discovery: Found device Volumio Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::volumioGetState Feb 18 11:54:35 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:35 volumio volumio[28769]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 11:54:35 volumio volumio[28769]: info: Discovery: Found device Volumio Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::volumioGetState Feb 18 11:54:35 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:35 volumio volumio[28769]: info: MPD Permissions set Feb 18 11:54:35 volumio volumio[28769]: info: MPD Permissions set Feb 18 11:54:35 volumio volumio[28769]: info: Upmpdcli Daemon Started Feb 18 11:54:35 volumio volumio[28769]: info: Spotify config file written Feb 18 11:54:35 volumio volumio[28769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 11:54:35 volumio volumio[28769]: info: Volumio called home Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio sudo[29028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 11:54:35 volumio sudo[29028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:35 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:35 volumio go-librespot[29035]: go-librespot daemon starting... Feb 18 11:54:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:36 volumio sudo[29028]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:36 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 11:54:36 volumio volumio[28769]: info: No need to fix Spotify hosts Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=debug msg="app state loaded" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=info msg="zeroconf server listening on port 41791" Feb 18 11:54:36 volumio go-librespot[29041]: time="2026-02-18T11:54:36+07:00" level=debug msg="obtained new client token: AABHeYNMqVffLtVVjOXbOUrO34T8c+0tZ38Mj83Y8pefxH8+LYOEKig5j214GslYjxVFVDc4uyMvnhkEsyNhQFO1ah6cTfSVAySyeLvliigSF6LXg9KiddTxf5NqSP0Q7U2ylmhOoBQYtG0lBZWuwmxhCyeUYhp+IWgb1uQP7dbF4BSTEO51Pn66kJUWzmk1rSOHTa+hi7GHoGi2KSH8qaLJhPRAYdbOcgJ2MwHmsRe5/09LbigDkwixOg==" Feb 18 11:54:37 volumio go-librespot[29041]: time="2026-02-18T11:54:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:37 volumio go-librespot[29041]: time="2026-02-18T11:54:37+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:37 volumio go-librespot[29041]: time="2026-02-18T11:54:37+07:00" level=debug msg="completed challenge" Feb 18 11:54:37 volumio go-librespot[29041]: time="2026-02-18T11:54:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:37 volumio volumio[28769]: info: Starting Shairport Sync Feb 18 11:54:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:37 volumio volumio[28769]: info: Starting Shairport Sync Feb 18 11:54:37 volumio volumio[28769]: info: Starting Shairport Sync Feb 18 11:54:37 volumio sudo[29067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:54:37 volumio sudo[29069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:54:37 volumio sudo[29069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:37 volumio sudo[29067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:37 volumio sudo[29071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 11:54:37 volumio sudo[29071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:37 volumio volumio[28769]: info: CoreCommandRouter::volumioGetState Feb 18 11:54:37 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:37 volumio volumio[28769]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 11:54:37 volumio volumio[28769]: SPOTIFY: BQAk7MxcTNLyEfHu_UmO6FJqWkGp_0otOx3Rom8KG1W4v6TyyRTMsBXGLorJNss8RlT0gphTI9gXxn8DAjlFvKIoqPXuR49gcVP5d0S8oZTgihZCX-Bp7oFXahAle7PDI_0TM2Pe7_Ru_rOJNDI0VkOpJea84dVIIWZQmUcFGR7-P6HNrqFhGLjfGGX6pO51KIzynvLB5GFVEPCffx6UQkssyTXZa1_EYEzmg3YReDd2EAHiF2edlnq1uIc9z24PzUN9F5MOFsQh2T0zdJvytKyGgH2TWZc9wM6zGEK09w9ua8ckaboaDfcu Feb 18 11:54:37 volumio volumio[28769]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 11:54:37 volumio volumio[28769]: info: New Spotify access token = BQAk7MxcTNLyEfHu_UmO6FJqWkGp_0otOx3Rom8KG1W4v6TyyRTMsBXGLorJNss8RlT0gphTI9gXxn8DAjlFvKIoqPXuR49gcVP5d0S8oZTgihZCX-Bp7oFXahAle7PDI_0TM2Pe7_Ru_rOJNDI0VkOpJea84dVIIWZQmUcFGR7-P6HNrqFhGLjfGGX6pO51KIzynvLB5GFVEPCffx6UQkssyTXZa1_EYEzmg3YReDd2EAHiF2edlnq1uIc9z24PzUN9F5MOFsQh2T0zdJvytKyGgH2TWZc9wM6zGEK09w9ua8ckaboaDfcu Feb 18 11:54:37 volumio volumio[28769]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 11:54:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 11:54:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 11:54:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 11:54:37 volumio systemd[1]: shairport-sync.service: Consumed 2.456s CPU time. Feb 18 11:54:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 11:54:37 volumio sudo[29069]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:37 volumio sudo[29067]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:37 volumio sudo[29071]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:37 volumio volumio[28769]: info: Shairport-Sync Started Feb 18 11:54:37 volumio volumio[28769]: Error adding Membership: Error: addMembership EINVAL Feb 18 11:54:37 volumio volumio[28769]: info: Shairport-Sync Started Feb 18 11:54:37 volumio volumio[28769]: info: Shairport-Sync Started Feb 18 11:54:38 volumio volumio[28769]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 18 11:54:38 volumio volumio[28769]: info: Spotify Successfully logged in Feb 18 11:54:38 volumio volumio[28769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 11:54:38 volumio volumio[28769]: info: [1771390478128] CoreMusicLibrary::Adding element Spotify Feb 18 11:54:38 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 11:54:38 volumio volumio[28769]: Cannot find translation for source YouTube Music Feb 18 11:54:38 volumio volumio[28769]: Cannot find translation for source Spotify Feb 18 11:54:38 volumio volumio[28769]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 11:54:38 volumio volumio[28769]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 11:54:39 volumio volumio[28769]: info: VolumeController:: Volume=100 Mute =false Feb 18 11:54:39 volumio volumio[28769]: info: CoreCommandRouter::volumioGetState Feb 18 11:54:39 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:39 volumio volumio[28769]: info: CoreStateMachine::pushState Feb 18 11:54:39 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:39 volumio volumio[28769]: info: CoreCommandRouter::volumioPushState Feb 18 11:54:40 volumio mpd[29003]: 2026-02-18T11:54:40 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 11:54:40 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 11:54:40 volumio sudo[28980]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:40 volumio sudo[28960]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:40 volumio volumio[28769]: info: go-librespot daemon successfully initialized Feb 18 11:54:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 18 11:54:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:40 volumio go-librespot[29111]: go-librespot daemon starting... Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=debug msg="app state loaded" Feb 18 11:54:40 volumio volumio[28769]: error: MPD error: The expression evaluated to a falsy value: Feb 18 11:54:40 volumio volumio[28769]: assert.ok(self.idling) Feb 18 11:54:40 volumio volumio[28769]: error: The expression evaluated to a falsy value: Feb 18 11:54:40 volumio volumio[28769]: assert.ok(self.idling) Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:40 volumio volumio[28769]: error: updateQueue error: null Feb 18 11:54:40 volumio volumio[28769]: info: MPD running with PID29003 Feb 18 11:54:40 volumio volumio[28769]: ,establishing connection Feb 18 11:54:40 volumio volumio[28769]: info: Completed starting Core Plugins Feb 18 11:54:40 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:40 volumio volumio[28769]: info: ----- MyVolumio plugins startup ---- Feb 18 11:54:40 volumio volumio[28769]: info: ------------------------------------------- Feb 18 11:54:40 volumio volumio[28769]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 11:54:40 volumio volumio[28769]: error: updateQueue error: null Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 18 11:54:40 volumio go-librespot[29112]: time="2026-02-18T11:54:40+07:00" level=info msg="zeroconf server listening on port 39577" Feb 18 11:54:41 volumio go-librespot[29112]: time="2026-02-18T11:54:41+07:00" level=debug msg="obtained new client token: AACy1ds5+E6VGw4tRvuuF3kNGEbcrfo4KKj/dnghm5sm2SuEIdz4/AqfiY5j5Ct4LqUmdzgH6QxFzIRMSZlEMWuuN2rpx8TADpXN8HimkyPgLkR2PX7wETjOfVgx1zQ5RzTMpJDhR5xLxxMje5k1ksjSy17fvYZPj6iFV/RwlhjSCEGy0OQQFNzUa+r8Tf/v6NuCXab930EUiv5tBuTCOAtZehMRNd1tfkL+de7M5rlWmMjx+J1Q/RQ=" Feb 18 11:54:41 volumio go-librespot[29112]: time="2026-02-18T11:54:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:41 volumio go-librespot[29112]: time="2026-02-18T11:54:41+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:41 volumio go-librespot[29112]: time="2026-02-18T11:54:41+07:00" level=debug msg="completed challenge" Feb 18 11:54:41 volumio go-librespot[29112]: time="2026-02-18T11:54:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:43 volumio volumio[28769]: info: Initializing connection to go-librespot Websocket Feb 18 11:54:43 volumio volumio[28769]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:54:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 18 11:54:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:44 volumio go-librespot[29121]: go-librespot daemon starting... Feb 18 11:54:44 volumio go-librespot[29122]: time="2026-02-18T11:54:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:44 volumio go-librespot[29122]: time="2026-02-18T11:54:44+07:00" level=debug msg="app state loaded" Feb 18 11:54:44 volumio go-librespot[29122]: time="2026-02-18T11:54:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=info msg="zeroconf server listening on port 39739" Feb 18 11:54:45 volumio volumio[28769]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="obtained new client token: AACEAmV85xXyEkCkwEoXxi5ENBsxSz/t8h3zfNey67VIfYfelv0G6E7BTK52aqj/R4JEL6TffbM86k3SX73H5iRxhRPEXYZJ+DsZJjNrimlUwGk2bE0ibLi9ujU7oLzwda40qsrs0h/J5M6+RfeH8TFSLJe2jRzv6A/fLb3lw46flwccD9AvKsvSILw2PT88sxGs5lnXyz9zypUTJqU5OnqAPKctlLtDcIpT7VOSQoh5s0izddLJCWV0jg==" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=debug msg="completed challenge" Feb 18 11:54:45 volumio go-librespot[29122]: time="2026-02-18T11:54:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:46 volumio volumio[28769]: info: Initializing connection to go-librespot Websocket Feb 18 11:54:46 volumio volumio[28769]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 11:54:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 18 11:54:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:48 volumio go-librespot[29131]: go-librespot daemon starting... Feb 18 11:54:48 volumio go-librespot[29132]: time="2026-02-18T11:54:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:48 volumio go-librespot[29132]: time="2026-02-18T11:54:48+07:00" level=debug msg="app state loaded" Feb 18 11:54:48 volumio go-librespot[29132]: time="2026-02-18T11:54:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:49 volumio volumio[28769]: info: Initializing connection to go-librespot Websocket Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=debug msg="new websocket client" Feb 18 11:54:49 volumio volumio[28769]: info: Connection to go-librespot Websocket established Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54: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 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54: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 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54: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 18 11:54:49 volumio volumio[28769]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 11:54:49 volumio volumio[28769]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=info msg="zeroconf server listening on port 44963" Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=debug msg="obtained new client token: AAArRSDVQK00A6pYBXDvggbsui5K8WNYvKvwutsoUWxiH/+952+3N96N6j/qOFwWTUnD9vnoU7PHDGMR/53BdHDXb3xrq4+t5e+KKxHpmq+UJa0QbzKA6eb2SMLX4S//lI8i5yWU+4tYyUKBgR9WkcC6vO89aB6r2ko3rhZn1avn58PWlXr5GkyIcjcypJrjFg6irk5+ckWxfjzf2mbrbHWOdG7iLWYoPXvPv4ZLcQTOTYKUIBN+WjmkLw==" Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=debug msg="completed challenge" Feb 18 11:54:49 volumio go-librespot[29132]: time="2026-02-18T11:54:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:51 volumio volumio[28769]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 11:54:51 volumio volumio[28769]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 11:54:51 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:51 volumio volumio[28769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 11:54:51 volumio volumio[28769]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 11:54:51 volumio volumio[28769]: info: MyVolumio login type: Token Feb 18 11:54:51 volumio volumio[28769]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 11:54:51 volumio volumio[28769]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 11:54:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 18 11:54:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:53 volumio volumio[28769]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 11:54:53 volumio volumio[28769]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 11:54:53 volumio volumio[28769]: info: Streaming services startup Feb 18 11:54:53 volumio volumio[28769]: info: Starting Streaming Daemon Feb 18 11:54:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 11:54:53 volumio go-librespot[29155]: go-librespot daemon starting... Feb 18 11:54:53 volumio sudo[29157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 11:54:53 volumio sudo[29157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=debug msg="app state loaded" Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 11:54:53 volumio volumio[28769]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 11:54:53 volumio sudo[29157]: pam_unix(sudo:session): session closed for user root Feb 18 11:54:53 volumio volumio[28769]: info: Getting Spotify volume Feb 18 11:54:53 volumio volumio[28769]: info: Connection to go-librespot Websocket closed Feb 18 11:54:53 volumio volumio[28769]: error: Cannot start Volumio Streaming Daemon Feb 18 11:54:53 volumio volumio[28769]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 11:54:53 volumio volumio[28769]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 11:54:53 volumio volumio[28769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 18 11:54:53 volumio volumio[28769]: info: CoreCommandRouter::volumioGetState Feb 18 11:54:53 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:53 volumio volumio[28769]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 11:54:53 volumio volumio[28769]: SPOTIFY: SPOTIFY VOLUME undefined Feb 18 11:54:53 volumio volumio[28769]: SPOTIFY: VOLUMIO VOLUME 100 Feb 18 11:54:53 volumio volumio[28769]: info: Aligning Spotify Volume to Volumio Volume Feb 18 11:54:53 volumio volumio[28769]: info: CoreCommandRouter::volumioGetState Feb 18 11:54:53 volumio volumio[28769]: info: CorePlayQueue::getTrack 0 Feb 18 11:54:53 volumio volumio[28769]: info: Setting Spotify Volume from Volumio: 100 Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=info msg="zeroconf server listening on port 43427" Feb 18 11:54:53 volumio go-librespot[29158]: time="2026-02-18T11:54:53+07:00" level=debug msg="obtained new client token: AADv5JQs34w8COGWczYSkArxc6Z/5cjlEDuodXFjpp895HCKE2GG1GVoj/EUIfaZHv9j6rf51qUgcpuguUN0m6DV+Swd4mGPWPChMVyRZLRs6S6p5vrCIcDrK32t9rrttpLSZPwZh0tvYb0kej+3xOYKozf+kmeEEiaDNlTQUOWRzpX3e9h8l0khhHJLalDLXu5wrH07q7F8bwL99yOhOUzNBNkP4L0uioDT07hwi035cBsCbiA+9l6meg==" Feb 18 11:54:54 volumio go-librespot[29158]: time="2026-02-18T11:54:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 11:54:54 volumio go-librespot[29158]: time="2026-02-18T11:54:54+07:00" level=debug msg="completed keyexchange" Feb 18 11:54:54 volumio go-librespot[29158]: time="2026-02-18T11:54:54+07:00" level=debug msg="completed challenge" Feb 18 11:54:54 volumio go-librespot[29158]: time="2026-02-18T11:54:54+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 11:54:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 11:54:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 11:54:54 volumio volumio[28769]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:54:54 volumio volumio[28769]: Error: socket hang up Feb 18 11:54:54 volumio volumio[28769]: at connResetException (node:internal/errors:720:14) Feb 18 11:54:54 volumio volumio[28769]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 18 11:54:54 volumio volumio[28769]: at Socket.emit (node:events:526:35) Feb 18 11:54:54 volumio volumio[28769]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 18 11:54:54 volumio volumio[28769]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 18 11:54:54 volumio volumio[28769]: code: 'ECONNRESET', Feb 18 11:54:54 volumio volumio[28769]: response: undefined Feb 18 11:54:54 volumio volumio[28769]: } Feb 18 11:54:54 volumio volumio[28769]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:54:55 volumio sudo[29185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 11:53' Feb 18 11:54:55 volumio sudo[29185]: 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"