Feb 17 19:50:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 17 19:50:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:00 volumio go-librespot[7325]: go-librespot daemon starting... Feb 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+07:00" level=debug msg="app state loaded" Feb 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+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 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+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 17 19:50:00 volumio go-librespot[7326]: time="2026-02-17T19:50:00+07:00" level=info msg="zeroconf server listening on port 46615" Feb 17 19:50:01 volumio go-librespot[7326]: time="2026-02-17T19:50:01+07:00" level=debug msg="obtained new client token: AADbtvITJAsHR3YL0V7pw0odaL6Ww+Y1Aya9qEXXpBl2RDDHQ53lHH+N23Yo8e+I4IwaS98GTGJamk0ute9ZFX2VmdDKYdsdEgF9gtjHWGgT348uykdndnZgdRRpsppHtUPKUsYHnPKKZb1h/qYWflRpozUeCgwlV5kFmvbFHcl7373ZZtIqech94g+gXWqGVbkY44rENF9/Y/32WoXrKQhJEag81Cagr1qonapMWGCZT1fbb/ZbCcxvBA==" Feb 17 19:50:01 volumio go-librespot[7326]: time="2026-02-17T19:50:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:01 volumio go-librespot[7326]: time="2026-02-17T19:50:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:01 volumio volumio[7251]: info: Loading plugin "ytcr"... Feb 17 19:50:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 17 19:50:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:04 volumio go-librespot[7334]: go-librespot daemon starting... Feb 17 19:50:04 volumio volumio[7251]: info: Loading plugin "ytmusic"... Feb 17 19:50:04 volumio go-librespot[7335]: time="2026-02-17T19:50:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:04 volumio go-librespot[7335]: time="2026-02-17T19:50:04+07:00" level=debug msg="app state loaded" Feb 17 19:50:04 volumio go-librespot[7335]: time="2026-02-17T19:50:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=info msg="zeroconf server listening on port 38085" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="obtained new client token: AACvYOqHQcJZ8juqVXrSonQijVioKqHpzRubtV69clWLIb0EfNXZOWmZ+S2KWi8RnrZTCRwXAWj5FA7R9n6uSg1akZEClCaHjYtHa/ILPCErqba/W5/Gg9SYrhZt1T/HTIF6jR0ZT9TjKnxgg9OB7hraU7zo50Yn/S4Nv56de0OeQxC2g+XN7fN0gcnWXIFoYBq9IvNTQl/mVdNUb8wuut4K/JayA/5VcVhoDB+C6TFXhZHYyG5/twU=" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:05 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:05] [connect] Successful connection Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="completed keyexchange" Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=debug msg="completed challenge" Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "outputs"... Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "albumart"... Feb 17 19:50:05 volumio go-librespot[7335]: time="2026-02-17T19:50:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 19:50:05 volumio volumio[7251]: info: Plugin example_plugin is not enabled Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "inputs"... Feb 17 19:50:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "updater_comm"... Feb 17 19:50:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:05 volumio volumio[7251]: info: Plugin mpdemulation is not enabled Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "rest_api"... Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "websocket"... Feb 17 19:50:05 volumio volumio[7251]: info: Starting Socket.io Server version 1.7.4 Feb 17 19:50:05 volumio volumio[7251]: info: Loading plugin "RoonBridge"... Feb 17 19:50:06 volumio volumio[7251]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 19:50:06 volumio volumio[7251]: info: Loading i18n strings for locale en Feb 17 19:50:06 volumio volumio[7251]: Updating browse sources language Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::initPlayerControls Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 19:50:06 volumio volumio[7251]: Express server listening on port 3000 Feb 17 19:50:06 volumio volumio[7251]: [Metrics] WebUI: 15s 936.59ms Feb 17 19:50:06 volumio volumio[7251]: info: CoreStateMachine::resetVolumioState Feb 17 19:50:06 volumio volumio[7251]: info: CoreStateMachine::getcurrentVolume Feb 17 19:50:06 volumio volumio[7251]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 19:50:06 volumio volumio[7356]: Forking 3 albumart workers Feb 17 19:50:06 volumio sudo[7372]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 19:50:06 volumio sudo[7372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:06 volumio sudo[7372]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:06 volumio sudo[7375]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 19:50:06 volumio sudo[7375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:06 volumio sudo[7375]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:06 volumio volumio[7251]: info: Volumio Network Manager: Network status updated: 1 Feb 17 19:50:07 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771332605 101 Feb 17 19:50:07 volumio volumio[7251]: 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 17 19:50:07 volumio volumio[7251]: info: VolumeController:: Volume=100 Mute =false Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::pushState Feb 17 19:50:07 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::updateTrackBlock Feb 17 19:50:07 volumio volumio[7251]: info: CorePlayQueue::getTrackBlock Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:07 volumio volumio[7251]: info: Reloading queue from file Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::setRepeat null single undefined Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::pushState Feb 17 19:50:07 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::setRandom null Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::pushState Feb 17 19:50:07 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:07 volumio volumio[7251]: info: Setting Device type: Raspberry PI Feb 17 19:50:07 volumio volumio[7251]: info: Completed loading Core Plugins Feb 17 19:50:07 volumio volumio[7251]: info: Preparing to generate the ALSA configuration file Feb 17 19:50:07 volumio volumio[7251]: info: VolumeController:: Volume=100 Mute =false Feb 17 19:50:07 volumio volumio[7251]: info: CoreStateMachine::pushState Feb 17 19:50:07 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:07 volumio volumio[7251]: info: Asound.conf file unchanged, so no further update is needed Feb 17 19:50:07 volumio volumio[7251]: info: Output device has changed, restarting MPD Feb 17 19:50:07 volumio sudo[7416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 19:50:07 volumio sudo[7416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:07 volumio volumio[7251]: info: Output device has changed, restarting Shairport Sync Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:07 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:07 volumio sudo[7421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 19:50:07 volumio sudo[7421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:07 volumio sudo[7418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 19:50:07 volumio sudo[7418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:07 volumio sudo[7416]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:07 volumio sudo[7418]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:08 volumio volumio[7251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 19:50:08 volumio volumio[7251]: info: ___________ START PLUGINS ___________ Feb 17 19:50:08 volumio volumio[7251]: info: ControllerMpd::onStart: Initializing MPD Feb 17 19:50:08 volumio volumio[7251]: info: Creating MPD Configuration file Feb 17 19:50:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:08 volumio volumio[7251]: info: [1771332608158] CoreMusicLibrary::Adding element Media Servers Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:08 volumio sudo[7429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 19:50:08 volumio sudo[7429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:08 volumio sudo[7429]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:08 volumio volumio[7251]: info: UPNP Browser: Client initialized successfully Feb 17 19:50:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 19:50:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 19:50:08 volumio sudo[7431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 19:50:08 volumio systemd[1]: mpd.service: Consumed 7.130s CPU time. Feb 17 19:50:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 19:50:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 19:50:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 19:50:08 volumio sudo[7431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 19:50:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 19:50:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 19:50:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 19:50:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 19:50:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 19:50:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 19:50:08 volumio volumio[7251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 19:50:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 19:50:08 volumio volumio[7251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:08 volumio volumio[7251]: info: [1771332608568] CoreMusicLibrary::Adding element Last_100 Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:08 volumio volumio[7251]: info: [1771332608583] CoreMusicLibrary::Adding element Webradio Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 19:50:08 volumio volumio[7251]: info: Initializing BBC Radios Feb 17 19:50:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 17 19:50:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:08 volumio volumio[7251]: info: Creating Spotify config file Feb 17 19:50:08 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:08 volumio go-librespot[7459]: go-librespot daemon starting... Feb 17 19:50:08 volumio go-librespot[7460]: time="2026-02-17T19:50:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:09 volumio sudo[7442]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 19:50:09 volumio sudo[7442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 19:50:09 volumio sudo[7442]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:09 volumio go-librespot[7460]: time="2026-02-17T19:50:09+07:00" level=info msg="zeroconf server listening on port 44827" Feb 17 19:50:10 volumio volumio[7376]: Starting albumart workers Feb 17 19:50:10 volumio volumio[7251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:10 volumio volumio[7251]: info: [1771332610065] CoreMusicLibrary::Adding element YouTube Music Feb 17 19:50:10 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:10 volumio volumio[7251]: Cannot find translation for source YouTube Music Feb 17 19:50:10 volumio volumio[7251]: info: Volumio Calling Home Feb 17 19:50:10 volumio volumio[7374]: Starting albumart workers Feb 17 19:50:10 volumio sudo[7470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 19:50:10 volumio sudo[7470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:10 volumio sudo[7470]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:10 volumio volumio[7371]: Starting albumart workers Feb 17 19:50:10 volumio volumio[7251]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 19:50:10 volumio volumio[7251]: info: Discovery: Found device Volumio Feb 17 19:50:10 volumio volumio[7251]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:10 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:11 volumio volumio[7251]: info: MPD Permissions set Feb 17 19:50:11 volumio volumio[7251]: info: MPD Permissions set Feb 17 19:50:11 volumio volumio[7251]: info: Upmpdcli Daemon Started Feb 17 19:50:11 volumio volumio[7251]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 19:50:11 volumio volumio[7251]: info: Discovery: Found device Volumio Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:11 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:11 volumio volumio[7251]: info: Volumio called home Feb 17 19:50:11 volumio volumio[7251]: info: Spotify config file written Feb 17 19:50:11 volumio sudo[7476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 19:50:11 volumio sudo[7476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:11 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 19:50:11 volumio systemd[1]: go-librespot-daemon.service: Killing process 7465 (go-librespot) with signal SIGKILL. Feb 17 19:50:11 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 19:50:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:11 volumio sudo[7476]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:11 volumio go-librespot[7479]: go-librespot daemon starting... Feb 17 19:50:11 volumio go-librespot[7485]: time="2026-02-17T19:50:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:11 volumio go-librespot[7485]: time="2026-02-17T19:50:11+07:00" level=debug msg="app state loaded" Feb 17 19:50:11 volumio go-librespot[7485]: time="2026-02-17T19:50:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:11 volumio volumio[7251]: 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 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:11 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:12 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:12 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:12 volumio volumio[7251]: info: No need to fix Spotify hosts Feb 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+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 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+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 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+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 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+07:00" level=info msg="zeroconf server listening on port 39973" Feb 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+07:00" level=debug msg="obtained new client token: AAAOf+4jFfDvJfbRfujAMnhtIC8h6Tt223eveFZiV3Svy4EKLh2qH6dklSVGdtHpR3tmYst4sp7L2BSfawT/MVy5RNP50PknAQIX7omxaniRGWx9ZyEJ4kIKPiAsKzqVq4PeYoF6sKaQDnBV+IuLau0DkhYNseppBLGMF+txSu68keQwxfHbVPo8+/PjUHMGYe60v5h/6/bfWUmYegWOpnYvUOTQlKIrymXeo3K5+aFa7376pmJieQroIg==" Feb 17 19:50:12 volumio volumio[7251]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:12 volumio volumio[7251]: SPOTIFY: BQCIkmOnIPSnBK3eA9mr78n4ew3q-_cVEmnOyF8lYFs2N5XxmpTxKPucd1HzGwK4knjMehozWV2j-kvC8VcFHX-usOh5hJxrG-e9tUM78L5qjsH0_H8NMwdrRkMjTTmpqpxEK-pxjxzM4Pk4F-yoLAtkgREJbwHIDToDC575PPLSNFHNV18p5pwOfZCqXaRHWNYdeupwsk4K5_bEUoMTNdl-glf8dXB7eUg0DNqTMv5cyao7T3UrS8uJGMuRH_7eNpBEAgqtolRsAqFKLrr8axrB5XWMYhEwKFMHb-q6XK4HY118jHalZnkx Feb 17 19:50:12 volumio volumio[7251]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 19:50:12 volumio volumio[7251]: info: New Spotify access token = BQCIkmOnIPSnBK3eA9mr78n4ew3q-_cVEmnOyF8lYFs2N5XxmpTxKPucd1HzGwK4knjMehozWV2j-kvC8VcFHX-usOh5hJxrG-e9tUM78L5qjsH0_H8NMwdrRkMjTTmpqpxEK-pxjxzM4Pk4F-yoLAtkgREJbwHIDToDC575PPLSNFHNV18p5pwOfZCqXaRHWNYdeupwsk4K5_bEUoMTNdl-glf8dXB7eUg0DNqTMv5cyao7T3UrS8uJGMuRH_7eNpBEAgqtolRsAqFKLrr8axrB5XWMYhEwKFMHb-q6XK4HY118jHalZnkx Feb 17 19:50:12 volumio volumio[7251]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 19:50:12 volumio volumio[7251]: info: Starting Shairport Sync Feb 17 19:50:12 volumio go-librespot[7485]: time="2026-02-17T19:50:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:13 volumio volumio[7251]: info: Starting Shairport Sync Feb 17 19:50:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:13 volumio volumio[7251]: info: Starting Shairport Sync Feb 17 19:50:13 volumio sudo[7516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 19:50:13 volumio sudo[7516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:13 volumio sudo[7518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 19:50:13 volumio sudo[7518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:13 volumio sudo[7520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 19:50:13 volumio sudo[7520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 19:50:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 19:50:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 19:50:13 volumio systemd[1]: shairport-sync.service: Consumed 2.424s CPU time. Feb 17 19:50:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 19:50:13 volumio sudo[7516]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:13 volumio sudo[7518]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:13 volumio volumio[7251]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:13 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 19:50:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 19:50:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 19:50:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 19:50:13 volumio sudo[7520]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:13 volumio volumio[7251]: info: Shairport-Sync Started Feb 17 19:50:13 volumio volumio[7251]: Error adding Membership: Error: addMembership EINVAL Feb 17 19:50:13 volumio volumio[7251]: info: Shairport-Sync Started Feb 17 19:50:13 volumio volumio[7251]: info: Shairport-Sync Started Feb 17 19:50:13 volumio volumio[7251]: 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 17 19:50:13 volumio volumio[7251]: info: Spotify Successfully logged in Feb 17 19:50:13 volumio volumio[7251]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:13 volumio volumio[7251]: info: [1771332613744] CoreMusicLibrary::Adding element Spotify Feb 17 19:50:13 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:13 volumio volumio[7251]: Cannot find translation for source YouTube Music Feb 17 19:50:13 volumio volumio[7251]: Cannot find translation for source Spotify Feb 17 19:50:14 volumio volumio[7251]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 19:50:14 volumio volumio[7251]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 19:50:14 volumio volumio[7251]: info: VolumeController:: Volume=100 Mute =false Feb 17 19:50:14 volumio volumio[7251]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:14 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:14 volumio volumio[7251]: info: CoreStateMachine::pushState Feb 17 19:50:14 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:14 volumio volumio[7251]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:15 volumio volumio[7251]: info: go-librespot daemon successfully initialized Feb 17 19:50:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 19:50:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:16 volumio go-librespot[7557]: go-librespot daemon starting... Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=debug msg="app state loaded" Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:16 volumio mpd[7467]: 2026-02-17T19:50:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 19:50:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 19:50:16 volumio sudo[7431]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:16 volumio sudo[7421]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:16 volumio volumio[7251]: error: MPD error: The expression evaluated to a falsy value: Feb 17 19:50:16 volumio volumio[7251]: assert.ok(self.idling) Feb 17 19:50:16 volumio volumio[7251]: error: The expression evaluated to a falsy value: Feb 17 19:50:16 volumio volumio[7251]: assert.ok(self.idling) Feb 17 19:50:16 volumio volumio[7251]: error: updateQueue error: null Feb 17 19:50:16 volumio volumio[7251]: info: MPD running with PID7467 Feb 17 19:50:16 volumio volumio[7251]: ,establishing connection Feb 17 19:50:16 volumio volumio[7251]: info: Completed starting Core Plugins Feb 17 19:50:16 volumio volumio[7251]: info: ------------------------------------------- Feb 17 19:50:16 volumio volumio[7251]: info: ----- MyVolumio plugins startup ---- Feb 17 19:50:16 volumio volumio[7251]: info: ------------------------------------------- Feb 17 19:50:16 volumio volumio[7251]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 19:50:16 volumio volumio[7251]: error: updateQueue error: null Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=info msg="zeroconf server listening on port 40235" Feb 17 19:50:16 volumio go-librespot[7558]: time="2026-02-17T19:50:16+07:00" level=debug msg="obtained new client token: AAAyxbVKBvpY1b56ZX97llqkpRqE8/Oc9e2vLqf3iB0CKaw+SERswJt2eL01mNIWrVPj2+oiJgVWXIjvubcRy/rIRLVP+jVACixbUilV51ze4zPclXibyJkpT5naR+Vg+jSXUPOOij1lpD+i5chl/gVL0TVOlnhZ5kc4t846MXSroX908Rw5Vro+LsFU2E6yc9p3O9nJPfeVlDkWqH4zVdqCj9k2+xbKJLLJ9O5OQEOXnQ7ehU4LMB8cxQ==" Feb 17 19:50:17 volumio go-librespot[7558]: time="2026-02-17T19:50:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:17 volumio go-librespot[7558]: time="2026-02-17T19:50:17+07:00" level=debug msg="completed keyexchange" Feb 17 19:50:17 volumio go-librespot[7558]: time="2026-02-17T19:50:17+07:00" level=debug msg="completed challenge" Feb 17 19:50:17 volumio go-librespot[7558]: time="2026-02-17T19:50:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 19:50:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:18 volumio volumio[7251]: info: Initializing connection to go-librespot Websocket Feb 17 19:50:18 volumio volumio[7251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:50:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 19:50:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:20 volumio go-librespot[7569]: go-librespot daemon starting... Feb 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+07:00" level=debug msg="app state loaded" Feb 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+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 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+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 17 19:50:20 volumio go-librespot[7570]: time="2026-02-17T19:50:20+07:00" level=info msg="zeroconf server listening on port 39975" Feb 17 19:50:21 volumio go-librespot[7570]: time="2026-02-17T19:50:21+07:00" level=debug msg="obtained new client token: AACBZOXtkkVYRF++n0zBqpVS62ysNBQ+oVjyq1PHJ+vJ7he/eTMkMoJ8j+TiZvD1Hgukb1cMM8dP9QcndqSb6Zk5CnhfefDdq9srSY/kk0ie6vsot3QXrlg2Lw4rfXZbmbXAHM4x8Y5jT5xO9D+zHluDPj+VfpAEFcamjQZuIn+AZdPdRvpSP8fLm5cu7MAFpQTAHwWJwSKKo84GgIPigCK8poVoH4ouy7IjMuVGMXBJJge+YQ7QZU8=" Feb 17 19:50:21 volumio go-librespot[7570]: time="2026-02-17T19:50:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:21 volumio go-librespot[7570]: time="2026-02-17T19:50:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:21 volumio volumio[7251]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 19:50:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:21 volumio volumio[7251]: info: Initializing connection to go-librespot Websocket Feb 17 19:50:21 volumio volumio[7251]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:50:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 19:50:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:24 volumio go-librespot[7578]: go-librespot daemon starting... Feb 17 19:50:24 volumio go-librespot[7579]: time="2026-02-17T19:50:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:24 volumio go-librespot[7579]: time="2026-02-17T19:50:24+07:00" level=debug msg="app state loaded" Feb 17 19:50:24 volumio go-librespot[7579]: time="2026-02-17T19:50:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:24 volumio volumio[7251]: info: Initializing connection to go-librespot Websocket Feb 17 19:50:24 volumio go-librespot[7579]: time="2026-02-17T19:50:24+07:00" level=debug msg="new websocket client" Feb 17 19:50:24 volumio volumio[7251]: info: Connection to go-librespot Websocket established Feb 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+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 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+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 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+07:00" level=info msg="zeroconf server listening on port 40931" Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 19:50:25 volumio volumio[7251]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+07:00" level=debug msg="obtained new client token: AAAHme/FR9dxfPSHfuPfty3A3GAoUG095nmeTP76ETVH2eUcXmOHMjZw8WvvrYl4AGcJ0KNGQAs/NlqzsaXJkXLH+H26ohgvHbPTNLlr9hb2f2zivENU/q9QLUnXetgZ4uprqh/QdRLmawxvDqoEaD5l57Z3gfBA7Go4x+RCfkRdsA23tMvd5xE6Hkjzv99SxME4UH56rIJHmYET+Z9UiNaZ9b8pGV6YINsWo8XT7Ek0IBH9NT9CWSoHAA==" Feb 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:25 volumio go-librespot[7579]: time="2026-02-17T19:50:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:27 volumio volumio[7251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 19:50:27 volumio volumio[7251]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 19:50:27 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:27 volumio volumio[7251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:27 volumio volumio[7251]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 19:50:27 volumio volumio[7251]: info: MyVolumio login type: Token Feb 17 19:50:27 volumio volumio[7251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 19:50:27 volumio volumio[7251]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 19:50:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 19:50:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:28 volumio go-librespot[7600]: go-librespot daemon starting... Feb 17 19:50:28 volumio go-librespot[7601]: time="2026-02-17T19:50:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:28 volumio go-librespot[7601]: time="2026-02-17T19:50:28+07:00" level=debug msg="app state loaded" Feb 17 19:50:28 volumio go-librespot[7601]: time="2026-02-17T19:50:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:28 volumio volumio[7251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 19:50:28 volumio volumio[7251]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 19:50:28 volumio volumio[7251]: info: Streaming services startup Feb 17 19:50:28 volumio volumio[7251]: info: Starting Streaming Daemon Feb 17 19:50:29 volumio sudo[7610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 19:50:29 volumio sudo[7610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:29 volumio volumio[7251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 19:50:29 volumio sudo[7610]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:29 volumio volumio[7251]: info: Getting Spotify volume Feb 17 19:50:29 volumio volumio[7251]: info: Connection to go-librespot Websocket closed Feb 17 19:50:29 volumio volumio[7251]: error: Cannot start Volumio Streaming Daemon Feb 17 19:50:29 volumio volumio[7251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 19:50:29 volumio volumio[7251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 19:50:29 volumio volumio[7251]: 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 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+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 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+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 17 19:50:29 volumio volumio[7251]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+07:00" level=info msg="zeroconf server listening on port 38503" Feb 17 19:50:29 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:29 volumio volumio[7251]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 19:50:29 volumio volumio[7251]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 19:50:29 volumio volumio[7251]: SPOTIFY: VOLUMIO VOLUME 100 Feb 17 19:50:29 volumio volumio[7251]: info: Aligning Spotify Volume to Volumio Volume Feb 17 19:50:29 volumio volumio[7251]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:29 volumio volumio[7251]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:29 volumio volumio[7251]: info: Setting Spotify Volume from Volumio: 100 Feb 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+07:00" level=debug msg="obtained new client token: AAAj+mFJjNNBWAaTA+DcR3+0yEWfq8y0nA/TqhPtRy4EHqok6Wn8eYRwKFhMWPtGP4cJssHTcwybxktWq2xcQxvbU97g7LjnCB3pbg+P9/t032gCcHobU/andtKLXvLRQKsFFRnG8jtpGOC/d/DoYNJxDOaQURkvfPsTHjEi46HjaemzQLnXXGxQSVb+f/Rv0yFJiwdqaj4UJkrc9jOTQzgCggGA9MA7krhML7JCIaEh4s0PQL2SuX2Imw==" Feb 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:29 volumio go-librespot[7601]: time="2026-02-17T19:50:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.23:37528->104.199.241.202:4070: read: connection reset by peer" Feb 17 19:50:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:30 volumio volumio[7251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 19:50:30 volumio volumio[7251]: Error: socket hang up Feb 17 19:50:30 volumio volumio[7251]: at connResetException (node:internal/errors:720:14) Feb 17 19:50:30 volumio volumio[7251]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 19:50:30 volumio volumio[7251]: at Socket.emit (node:events:526:35) Feb 17 19:50:30 volumio volumio[7251]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 19:50:30 volumio volumio[7251]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 19:50:30 volumio volumio[7251]: code: 'ECONNRESET', Feb 17 19:50:30 volumio volumio[7251]: response: undefined Feb 17 19:50:30 volumio volumio[7251]: } Feb 17 19:50:30 volumio volumio[7251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 19:50:31 volumio sudo[7631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 19:49' Feb 17 19:50:31 volumio sudo[7631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:31 volumio sudo[7631]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 17 19:50:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:33 volumio go-librespot[7639]: go-librespot daemon starting... Feb 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+07:00" level=debug msg="app state loaded" Feb 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:33 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:33] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 17 19:50:33 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:33] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 17 19:50:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 19:50:33 volumio systemd[1]: volumio.service: Consumed 55.817s CPU time. Feb 17 19:50:33 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 19:50:33 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 19:50:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20807. Feb 17 19:50:33 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 19:50:33 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 19:50:33 volumio systemd[1]: volumio.service: Consumed 55.817s CPU time. Feb 17 19:50:33 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 19:50:33 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+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 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+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 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+07:00" level=info msg="zeroconf server listening on port 43621" Feb 17 19:50:33 volumio go-librespot[7640]: time="2026-02-17T19:50:33+07:00" level=debug msg="obtained new client token: AADqQEEcdBWhKBTuuBZdLYg70JwJVC9TfuPtJ9I8vPn277Oct5lpiigOByIZb0n+uNfAn9Nb1UI+dZNSJHYc0jKhl4MkrpVd1LI0cwF91yeUZDMi0jTaMRTdpxaHIUaWOeQwJwAXfx2iLZLi91UPD/aVfUsvtH+tmp0fpFznOMf+xdWoZtS7VugidJvt4wQu7nOH/cxnjt2U5ZsflOeRaEAcGU2EyRIuG6o+HyLMcIfW5WfmsN9IWkSCNg==" Feb 17 19:50:34 volumio go-librespot[7640]: time="2026-02-17T19:50:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:34 volumio go-librespot[7640]: time="2026-02-17T19:50:34+07:00" level=debug msg="completed keyexchange" Feb 17 19:50:34 volumio go-librespot[7640]: time="2026-02-17T19:50:34+07:00" level=debug msg="completed challenge" Feb 17 19:50:34 volumio go-librespot[7640]: time="2026-02-17T19:50:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 19:50:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:37 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:50:37 volumio volumio[7653]: info: ----- Volumio3 ---- Feb 17 19:50:37 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:50:37 volumio volumio[7653]: info: ----- System startup ---- Feb 17 19:50:37 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:50:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 17 19:50:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:37 volumio go-librespot[7675]: go-librespot daemon starting... Feb 17 19:50:37 volumio go-librespot[7676]: time="2026-02-17T19:50:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:37 volumio go-librespot[7676]: time="2026-02-17T19:50:37+07:00" level=debug msg="app state loaded" Feb 17 19:50:37 volumio go-librespot[7676]: time="2026-02-17T19:50:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:37 volumio go-librespot[7676]: time="2026-02-17T19:50:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:37 volumio go-librespot[7676]: time="2026-02-17T19:50:37+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 19:50:37 volumio go-librespot[7676]: time="2026-02-17T19:50:37+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 19:50:38 volumio go-librespot[7676]: time="2026-02-17T19:50:38+07:00" level=info msg="zeroconf server listening on port 42821" Feb 17 19:50:38 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:38] [connect] Successful connection Feb 17 19:50:38 volumio go-librespot[7676]: time="2026-02-17T19:50:38+07:00" level=debug msg="obtained new client token: AADmFgmAS2E+L7RyoniTX2hNWDf4iaOc+CG6ScfUUZd6jOL9o8w4sXT3y+IlAtRusYmr35alAqIR1jel2kb5AwCUGlr50HVbUKWjSen96M0zAHvnkVu7yi6hMezz4r/S0uk9u7k5V7fkMgjNoojof9Jp+vDoazynEQG82nWwvnNiMZNWOBdgoFjHhBic9iZHlRwzDbrEcfbk6eqKVaSwCZJ1idONgsMbb9Vh16yesrgqTdGF1DZev94=" Feb 17 19:50:38 volumio go-librespot[7676]: time="2026-02-17T19:50:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:38 volumio go-librespot[7676]: time="2026-02-17T19:50:38+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:38 volumio volumio[7653]: info: MYVOLUMIO Environment detected Feb 17 19:50:38 volumio volumio[7653]: info: Plugin folders cleanup Feb 17 19:50:38 volumio volumio[7653]: info: Scanning into folder /volumio/app/plugins/ Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category audio_interface Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category miscellanea Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category music_service Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category plugins.json Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category system_controller Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category user_interface Feb 17 19:50:38 volumio volumio[7653]: info: Scanning into folder /data/plugins/ Feb 17 19:50:38 volumio volumio[7653]: info: Scanning category music_service Feb 17 19:50:38 volumio volumio[7653]: info: Plugin folders cleanup completed Feb 17 19:50:38 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:50:38 volumio volumio[7653]: info: ----- Core plugins startup ---- Feb 17 19:50:38 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:50:38 volumio volumio[7653]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 19:50:38 volumio volumio[7653]: info: Adding plugin upnp to MyMusic Plugins Feb 17 19:50:38 volumio volumio[7653]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 19:50:38 volumio volumio[7653]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 19:50:38 volumio volumio[7653]: info: Loading plugins from folder /data/plugins/ Feb 17 19:50:38 volumio volumio[7653]: info: Loading plugin "system"... Feb 17 19:50:38 volumio volumio[7653]: info: Loading plugin "appearance"... Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "network"... Feb 17 19:50:40 volumio volumio[7653]: info: Refreshing Cached IP Addresses Feb 17 19:50:40 volumio sudo[7691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 19:50:40 volumio sudo[7691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:40 volumio sudo[7693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "services"... Feb 17 19:50:40 volumio sudo[7693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "alsa_controller"... Feb 17 19:50:40 volumio sudo[7691]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:40 volumio sudo[7693]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:40 volumio sudo[7700]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 19:50:40 volumio sudo[7700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:40 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "wizard"... Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "networkfs"... Feb 17 19:50:40 volumio volumio[7653]: info: Starting Udev Watcher for removable devices Feb 17 19:50:40 volumio volumio[7653]: info: Ignoring mount for partition: boot Feb 17 19:50:40 volumio volumio[7653]: info: Ignoring mount for partition: volumio Feb 17 19:50:40 volumio volumio[7653]: info: Ignoring mount for partition: volumio_data Feb 17 19:50:40 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "volumio_command_line_client"... Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "upnp"... Feb 17 19:50:40 volumio volumio[7653]: info: [1771332640959] Starting Upmpd Daemon Feb 17 19:50:40 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "my_music"... Feb 17 19:50:40 volumio volumio[7653]: info: Loading plugin "mpd"... Feb 17 19:50:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 17 19:50:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:41 volumio volumio[7653]: info: Loading plugin "upnp_browser"... Feb 17 19:50:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:41 volumio go-librespot[7724]: go-librespot daemon starting... Feb 17 19:50:41 volumio go-librespot[7725]: time="2026-02-17T19:50:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:41 volumio go-librespot[7725]: time="2026-02-17T19:50:41+07:00" level=debug msg="app state loaded" Feb 17 19:50:41 volumio go-librespot[7725]: time="2026-02-17T19:50:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=info msg="zeroconf server listening on port 39883" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=debug msg="obtained new client token: AABxf8ZPDS2RMh0Jmx7yrm6uPgq15r2BcW81gdmyXR0W/HMupbqnXqc03I2YOERHnu17QxCINIhTrS8Kj9Kx1Cb/ZHE3ECXu30LASSIpY08ozxnZQrVZyMZwt9mY60jIw2V6JrmGCoapAyrmluFuJalaVxKSX2ZO3HBkg1vgk+Q/cErtVUjwTQNMgO2tayS9dKYfKWzrLqBBMelpIWGHbnwMUWUI76QyxYsaX7WIWXjk2/Mh3/huv0FxiQ==" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+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 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 19:50:42 volumio go-librespot[7725]: time="2026-02-17T19:50:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:43 volumio sudo[7700]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:44 volumio volumio[7653]: info: Starting UPNP Browser Feb 17 19:50:44 volumio volumio[7653]: info: Loading plugin "alarm-clock"... Feb 17 19:50:44 volumio volumio[7653]: info: Loading plugin "airplay_emulation"... Feb 17 19:50:44 volumio volumio[7653]: info: Starting Shairport Sync Feb 17 19:50:44 volumio volumio[7653]: info: Loading plugin "last_100"... Feb 17 19:50:44 volumio volumio[7653]: info: Loading plugin "webradio"... Feb 17 19:50:45 volumio volumio[7653]: info: Loading plugin "i2s_dacs"... Feb 17 19:50:45 volumio volumio[7653]: info: Loading plugin "volumiodiscovery"... Feb 17 19:50:45 volumio volumio[7653]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 19:50:45 volumio volumio[7653]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 19:50:45 volumio node[7653]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 19:50:45 volumio volumio[7653]: *** WARNING *** For more information see Feb 17 19:50:45 volumio volumio[7653]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 19:50:45 volumio volumio[7653]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 19:50:45 volumio volumio[7653]: *** WARNING *** For more information see Feb 17 19:50:45 volumio node[7653]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 19:50:45 volumio node[7653]: *** WARNING *** For more information see Feb 17 19:50:45 volumio node[7653]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 19:50:45 volumio node[7653]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 19:50:45 volumio node[7653]: *** WARNING *** For more information see Feb 17 19:50:45 volumio volumio[7653]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 19:50:45 volumio volumio[7653]: info: Discovery: Started advertising with name: Volumio Feb 17 19:50:45 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 19:50:45 volumio volumio[7653]: info: Loading plugin "spop"... Feb 17 19:50:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 17 19:50:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:45 volumio go-librespot[7739]: go-librespot daemon starting... Feb 17 19:50:45 volumio go-librespot[7740]: time="2026-02-17T19:50:45+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:45 volumio go-librespot[7740]: time="2026-02-17T19:50:45+07:00" level=debug msg="app state loaded" Feb 17 19:50:45 volumio go-librespot[7740]: time="2026-02-17T19:50:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=info msg="zeroconf server listening on port 38111" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="obtained new client token: AACIfgHnPh78ng6DQGTBlV2E+h4iYSsndc6Ckf1hDBSOFJOYzYSevmWQQ+si+gNvrZZFDxzSiDSWV0eOM2MyKFfZj9mirfaEI22Fakgr7AKiJXzWqhQSix8GhLEa0u8vNwDwe9VlkBOd9KxAt3IStBp8VjNeNTBnLBnx0l5uN3LmstnxhOlXyXFH46QCYm/5wn/1kJ+UDCwe6L61qFr09qgN1lg+g5UIdQH+Jthuw3uldQrQD4eb+TW8tg==" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="completed keyexchange" Feb 17 19:50:46 volumio go-librespot[7740]: time="2026-02-17T19:50:46+07:00" level=debug msg="completed challenge" Feb 17 19:50:47 volumio go-librespot[7740]: time="2026-02-17T19:50:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 19:50:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:47 volumio volumio[7653]: info: Loading plugin "ytcr"... Feb 17 19:50:49 volumio volumio[7653]: info: Loading plugin "ytmusic"... Feb 17 19:50:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 17 19:50:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:50 volumio go-librespot[7748]: go-librespot daemon starting... Feb 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+07:00" level=debug msg="app state loaded" Feb 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+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 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+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 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+07:00" level=info msg="zeroconf server listening on port 34855" Feb 17 19:50:50 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:50] [connect] Successful connection Feb 17 19:50:50 volumio volumio[7653]: info: Loading plugin "outputs"... Feb 17 19:50:50 volumio volumio[7653]: info: Loading plugin "albumart"... Feb 17 19:50:50 volumio volumio[7653]: info: Plugin example_plugin is not enabled Feb 17 19:50:50 volumio volumio[7653]: info: Loading plugin "inputs"... Feb 17 19:50:50 volumio volumio[7653]: info: Loading plugin "updater_comm"... Feb 17 19:50:50 volumio go-librespot[7749]: time="2026-02-17T19:50:50+07:00" level=debug msg="obtained new client token: AACAsok2Xv/f91BwYBjumKl3427ZoB5/MwsLYo/s4OHS/R5FiK0lFhP8qmXplnfcsgcm55/AgNikDXAnPCFoBDqh3cjbBtoBMrRGU6ATz6qrNBytC1W2hsIad2ehUYz3FKLrAZjx8vlPGPJWPSztJg9nnw6edt0GTaZyqjNFknu2K9lXLqfti9piIzAySaM/CJFVXk2juH5I/6qMELDjFWYHDTxgvQoXhXuHSPYdt7bIMQk8VewnfF9rqw==" Feb 17 19:50:51 volumio go-librespot[7749]: time="2026-02-17T19:50:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:51 volumio go-librespot[7749]: time="2026-02-17T19:50:51+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:50:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:51 volumio volumio[7653]: info: Plugin mpdemulation is not enabled Feb 17 19:50:51 volumio volumio[7653]: info: Loading plugin "rest_api"... Feb 17 19:50:51 volumio volumio[7653]: info: Loading plugin "websocket"... Feb 17 19:50:51 volumio volumio[7653]: info: Starting Socket.io Server version 1.7.4 Feb 17 19:50:51 volumio volumio[7653]: info: Loading plugin "RoonBridge"... Feb 17 19:50:51 volumio volumio[7653]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 19:50:51 volumio volumio[7653]: info: Loading i18n strings for locale en Feb 17 19:50:51 volumio volumio[7653]: Updating browse sources language Feb 17 19:50:51 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:51 volumio volumio[7770]: Forking 3 albumart workers Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::initPlayerControls Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: Express server listening on port 3000 Feb 17 19:50:52 volumio volumio[7653]: [Metrics] WebUI: 16s 56.11ms Feb 17 19:50:52 volumio volumio[7653]: info: CoreStateMachine::resetVolumioState Feb 17 19:50:52 volumio volumio[7653]: info: CoreStateMachine::getcurrentVolume Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 19:50:52 volumio nmbd[966]: [2026/02/17 19:50:52.316469, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 17 19:50:52 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 17 19:50:52 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 17 19:50:52 volumio sudo[7816]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 19:50:52 volumio sudo[7816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:52 volumio sudo[7816]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:52 volumio sudo[7818]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 19:50:52 volumio sudo[7818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:52 volumio sudo[7818]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:52 volumio volumio[7653]: info: Volumio Network Manager: Network status updated: 1 Feb 17 19:50:52 volumio volumio[7653]: info: VolumeController:: Volume=100 Mute =false Feb 17 19:50:52 volumio volumio[7653]: info: CoreStateMachine::pushState Feb 17 19:50:52 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:52 volumio volumio[7653]: info: CoreStateMachine::updateTrackBlock Feb 17 19:50:52 volumio volumio[7653]: info: CorePlayQueue::getTrackBlock Feb 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 19:50:52 volumio volumio-remote-updater[25144]: [2026-02-17 19:50:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771332650 101 Feb 17 19:50:52 volumio volumio[7653]: 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 17 19:50:52 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:52 volumio volumio[7653]: info: Reloading queue from file Feb 17 19:50:53 volumio volumio[7653]: info: CoreStateMachine::setRepeat null single undefined Feb 17 19:50:53 volumio volumio[7653]: info: CoreStateMachine::pushState Feb 17 19:50:53 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:53 volumio volumio[7653]: info: CoreStateMachine::setRandom null Feb 17 19:50:53 volumio volumio[7653]: info: CoreStateMachine::pushState Feb 17 19:50:53 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:53 volumio volumio[7653]: info: Setting Device type: Raspberry PI Feb 17 19:50:53 volumio volumio[7653]: info: Completed loading Core Plugins Feb 17 19:50:53 volumio volumio[7653]: info: Preparing to generate the ALSA configuration file Feb 17 19:50:53 volumio volumio[7653]: info: VolumeController:: Volume=100 Mute =false Feb 17 19:50:53 volumio volumio[7653]: info: CoreStateMachine::pushState Feb 17 19:50:53 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::volumioPushState Feb 17 19:50:53 volumio volumio[7653]: info: Asound.conf file unchanged, so no further update is needed Feb 17 19:50:53 volumio volumio[7653]: info: Output device has changed, restarting MPD Feb 17 19:50:53 volumio volumio[7653]: info: Output device has changed, restarting Shairport Sync Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:53 volumio sudo[7831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 19:50:53 volumio sudo[7831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:53 volumio sudo[7833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 19:50:53 volumio sudo[7833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:53 volumio sudo[7833]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:53 volumio volumio[7653]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 19:50:53 volumio sudo[7835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 19:50:53 volumio sudo[7835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:53 volumio volumio[7653]: info: ___________ START PLUGINS ___________ Feb 17 19:50:53 volumio volumio[7653]: info: ControllerMpd::onStart: Initializing MPD Feb 17 19:50:53 volumio volumio[7653]: info: Creating MPD Configuration file Feb 17 19:50:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 19:50:53 volumio sudo[7831]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:53 volumio volumio[7653]: info: [1771332653751] CoreMusicLibrary::Adding element Media Servers Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:53 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 19:50:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 19:50:53 volumio systemd[1]: mpd.service: Consumed 7.241s CPU time. Feb 17 19:50:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 19:50:53 volumio sudo[7844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 19:50:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 19:50:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 19:50:53 volumio sudo[7844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:53 volumio sudo[7846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 19:50:53 volumio sudo[7846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:53 volumio sudo[7844]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:53 volumio volumio[7653]: info: UPNP Browser: Client initialized successfully Feb 17 19:50:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 19:50:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:53 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:53 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 19:50:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 19:50:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 19:50:54 volumio volumio[7653]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 19:50:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 19:50:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:54 volumio volumio[7653]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:54 volumio volumio[7653]: info: [1771332654091] CoreMusicLibrary::Adding element Last_100 Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 19:50:54 volumio volumio[7653]: info: [1771332654103] CoreMusicLibrary::Adding element Webradio Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 19:50:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 19:50:54 volumio volumio[7653]: info: Initializing BBC Radios Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 17 19:50:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:54 volumio volumio[7653]: info: Creating Spotify config file Feb 17 19:50:54 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:54 volumio go-librespot[7874]: go-librespot daemon starting... Feb 17 19:50:54 volumio go-librespot[7875]: time="2026-02-17T19:50:54+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:54 volumio sudo[7860]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 19:50:54 volumio sudo[7860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 19:50:54 volumio sudo[7860]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:55 volumio volumio[7780]: Starting albumart workers Feb 17 19:50:55 volumio volumio[7782]: Starting albumart workers Feb 17 19:50:55 volumio go-librespot[7875]: time="2026-02-17T19:50:55+07:00" level=info msg="zeroconf server listening on port 36263" Feb 17 19:50:55 volumio volumio[7781]: Starting albumart workers Feb 17 19:50:55 volumio volumio[7653]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:55 volumio volumio[7653]: info: [1771332655722] CoreMusicLibrary::Adding element YouTube Music Feb 17 19:50:55 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:55 volumio volumio[7653]: Cannot find translation for source YouTube Music Feb 17 19:50:55 volumio volumio[7653]: info: Volumio Calling Home Feb 17 19:50:55 volumio sudo[7886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 19:50:55 volumio sudo[7886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:56 volumio sudo[7886]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:56 volumio volumio[7653]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 19:50:56 volumio volumio[7653]: info: Discovery: Found device Volumio Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:56 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:56 volumio volumio[7653]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 19:50:56 volumio volumio[7653]: info: Discovery: Found device Volumio Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:56 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:56 volumio volumio[7653]: info: MPD Permissions set Feb 17 19:50:56 volumio volumio[7653]: info: MPD Permissions set Feb 17 19:50:56 volumio volumio[7653]: info: Upmpdcli Daemon Started Feb 17 19:50:56 volumio volumio[7653]: info: Spotify config file written Feb 17 19:50:56 volumio volumio[7653]: 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 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio sudo[7892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 19:50:56 volumio sudo[7892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:50:56 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 19:50:57 volumio volumio[7653]: info: No need to fix Spotify hosts Feb 17 19:50:57 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 19:50:57 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 19:50:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:50:57 volumio go-librespot[7904]: go-librespot daemon starting... Feb 17 19:50:57 volumio sudo[7892]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=debug msg="app state loaded" Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 19:50:57 volumio go-librespot[7911]: time="2026-02-17T19:50:57+07:00" level=info msg="zeroconf server listening on port 40119" Feb 17 19:50:58 volumio volumio[7653]: info: Volumio called home Feb 17 19:50:58 volumio volumio[7653]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 19:50:58 volumio volumio[7653]: SPOTIFY: BQBZQOeklVv7kAmZKKnno9Omw7bRoJqW1Wtqd6AO-779yDVaMZEfdz_dB9R8fcVrTCHCn4lLWnb7fxRy2GwWB-xwq6khp8Epv9lVVdb1Pge6NRDdF-KwbCv0bPUjEhph4Ic22N8vFoKA_PtNP3vGuAmsTygKCvaGmjDJDkEpN2pJrcZ_k6hecPzg90ZYP2OGWitH5QSB8VXuc83NzvtxukLKovUCzyoCg8cLoMz0I2aTItOAxFkb8jbOpEuBA5FPDX2Ka5mtVhqZJ3PGE_1QSreHsbPdY9Xa2Zl-8jkUrGY-_noqmd6ipphv Feb 17 19:50:58 volumio volumio[7653]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 19:50:58 volumio volumio[7653]: info: New Spotify access token = BQBZQOeklVv7kAmZKKnno9Omw7bRoJqW1Wtqd6AO-779yDVaMZEfdz_dB9R8fcVrTCHCn4lLWnb7fxRy2GwWB-xwq6khp8Epv9lVVdb1Pge6NRDdF-KwbCv0bPUjEhph4Ic22N8vFoKA_PtNP3vGuAmsTygKCvaGmjDJDkEpN2pJrcZ_k6hecPzg90ZYP2OGWitH5QSB8VXuc83NzvtxukLKovUCzyoCg8cLoMz0I2aTItOAxFkb8jbOpEuBA5FPDX2Ka5mtVhqZJ3PGE_1QSreHsbPdY9Xa2Zl-8jkUrGY-_noqmd6ipphv Feb 17 19:50:58 volumio volumio[7653]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 19:50:58 volumio volumio[7653]: info: Starting Shairport Sync Feb 17 19:50:58 volumio volumio[7653]: info: Starting Shairport Sync Feb 17 19:50:58 volumio go-librespot[7911]: time="2026-02-17T19:50:58+07:00" level=debug msg="obtained new client token: AADVYp9Li27Pr3HZ4UTlQnkRZGXKp8wJybkkTI3/RYNeG7WvhSUfgeA5/5/zPAZzsOCK0op8FUYptmu5Cl+NIEM1y/TKxs/ejQcuH08CyBVdFuXpPz32CQHC3x+ELTT2P4Cyj6yeU4Af8y8BR5TFPT0FW5q4+qF+qx8ET/400KBt5R0/TLzo30948uDgO8quGF3gKLgQJprrVK3HuO8cauQSAoeRj12aHUGtVR8+t5lbmwwB/BMWSeY=" Feb 17 19:50:58 volumio volumio[7653]: info: Starting Shairport Sync Feb 17 19:50:58 volumio sudo[7932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 19:50:58 volumio sudo[7932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:58 volumio sudo[7934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 19:50:58 volumio go-librespot[7911]: time="2026-02-17T19:50:58+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:50:58 volumio sudo[7934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:58 volumio sudo[7936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 19:50:58 volumio sudo[7936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:50:58 volumio go-librespot[7911]: time="2026-02-17T19:50:58+07:00" level=debug msg="completed keyexchange" Feb 17 19:50:58 volumio go-librespot[7911]: time="2026-02-17T19:50:58+07:00" level=debug msg="completed challenge" Feb 17 19:50:58 volumio go-librespot[7911]: time="2026-02-17T19:50: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 17 19:50:58 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 19:50:58 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 19:50:58 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 19:50:58 volumio systemd[1]: shairport-sync.service: Consumed 2.328s CPU time. Feb 17 19:50:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:50:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:50:58 volumio volumio[7653]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:58 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 19:50:58 volumio sudo[7936]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:58 volumio sudo[7934]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:58 volumio sudo[7932]: pam_unix(sudo:session): session closed for user root Feb 17 19:50:58 volumio volumio[7653]: info: Shairport-Sync Started Feb 17 19:50:58 volumio volumio[7653]: Error adding Membership: Error: addMembership EINVAL Feb 17 19:50:58 volumio volumio[7653]: info: Shairport-Sync Started Feb 17 19:50:58 volumio volumio[7653]: info: Shairport-Sync Started Feb 17 19:50:58 volumio volumio[7653]: 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 17 19:50:58 volumio volumio[7653]: info: Spotify Successfully logged in Feb 17 19:50:58 volumio volumio[7653]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 19:50:58 volumio volumio[7653]: info: [1771332658927] CoreMusicLibrary::Adding element Spotify Feb 17 19:50:58 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 19:50:58 volumio volumio[7653]: Cannot find translation for source YouTube Music Feb 17 19:50:58 volumio volumio[7653]: Cannot find translation for source Spotify Feb 17 19:50:59 volumio volumio[7653]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 19:50:59 volumio volumio[7653]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 19:50:59 volumio volumio[7653]: info: VolumeController:: Volume=100 Mute =false Feb 17 19:50:59 volumio volumio[7653]: info: CoreCommandRouter::volumioGetState Feb 17 19:50:59 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:59 volumio volumio[7653]: info: CoreStateMachine::pushState Feb 17 19:50:59 volumio volumio[7653]: info: CorePlayQueue::getTrack 0 Feb 17 19:50:59 volumio volumio[7653]: info: CoreCommandRouter::volumioPushState Feb 17 19:51:01 volumio volumio[7653]: info: go-librespot daemon successfully initialized Feb 17 19:51:01 volumio mpd[7882]: 2026-02-17T19:51:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 19:51:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 19:51:01 volumio sudo[7846]: pam_unix(sudo:session): session closed for user root Feb 17 19:51:01 volumio sudo[7835]: pam_unix(sudo:session): session closed for user root Feb 17 19:51:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 19:51:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:01 volumio volumio[7653]: error: MPD error: The expression evaluated to a falsy value: Feb 17 19:51:01 volumio volumio[7653]: assert.ok(self.idling) Feb 17 19:51:01 volumio volumio[7653]: error: The expression evaluated to a falsy value: Feb 17 19:51:01 volumio volumio[7653]: assert.ok(self.idling) Feb 17 19:51:01 volumio volumio[7653]: info: MPD running with PID7882 Feb 17 19:51:01 volumio volumio[7653]: ,establishing connection Feb 17 19:51:01 volumio volumio[7653]: error: updateQueue error: null Feb 17 19:51:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:01 volumio go-librespot[7977]: go-librespot daemon starting... Feb 17 19:51:01 volumio go-librespot[7978]: time="2026-02-17T19:51:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:01 volumio go-librespot[7978]: time="2026-02-17T19:51:01+07:00" level=debug msg="app state loaded" Feb 17 19:51:01 volumio go-librespot[7978]: time="2026-02-17T19:51:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:01 volumio volumio[7653]: info: Completed starting Core Plugins Feb 17 19:51:01 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:51:01 volumio volumio[7653]: info: ----- MyVolumio plugins startup ---- Feb 17 19:51:01 volumio volumio[7653]: info: ------------------------------------------- Feb 17 19:51:01 volumio volumio[7653]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 19:51:01 volumio volumio[7653]: error: updateQueue error: null Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+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 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+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 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+07:00" level=info msg="zeroconf server listening on port 37249" Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+07:00" level=debug msg="obtained new client token: AAAqO/N7B3dP6JjcCfS+xV1xgx/E9POehanFc3LHoaCUvG96Wwpb0ZdNGwMOI0RNEVsbnYV/cV2JK5jFxdjIRtSFpn1F7YPt7wllrprardLea/omNxmRG42lEeWVC14xkzwwrYHgB1Q46uL0fn2GLTd5wdaJxDBAfn4sj93sbwnzEHSsUpLSETvS+j228I5TxpP849Y+63UC//feryK+k/PF4I1un1M4kBjWKENysWL8LcSZePBAfWCZ2Q==" Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+07:00" level=debug msg="completed keyexchange" Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51:02+07:00" level=debug msg="completed challenge" Feb 17 19:51:02 volumio go-librespot[7978]: time="2026-02-17T19:51: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 17 19:51:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:51:04 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:04 volumio volumio[7653]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 19:51:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:05 volumio go-librespot[7985]: go-librespot daemon starting... Feb 17 19:51:05 volumio go-librespot[7986]: time="2026-02-17T19:51:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:05 volumio go-librespot[7986]: time="2026-02-17T19:51:05+07:00" level=debug msg="app state loaded" Feb 17 19:51:05 volumio go-librespot[7986]: time="2026-02-17T19:51:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51: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 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51: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 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51: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 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51:06+07:00" level=info msg="zeroconf server listening on port 40949" Feb 17 19:51:06 volumio volumio[7653]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51:06+07:00" level=debug msg="obtained new client token: AAA/l1di0KFtmOv+MXVrh+fgdNXnqJfZC/OnfkYkgm7Jwc++FXjJJov+HG3gpNlakYCG70+qQQkjf/2GNORMlW4O0dvqvBRcsZZPHkTxvTGE9a9lVoAhaSkPlqs4VY4c4RFKps7MsXbE1ic3l7HWBKS603I6w2UoNlWq86IFfBglwYzD8CUWE1A9En/74PAZsURDrzWT3fDCEnn+oPomP8OiAQ5UsNdn5lcokGG6Z8EruMJ2Rtt1naxVcg==" Feb 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51:06+07:00" level=debug msg="completed keyexchange" Feb 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51:06+07:00" level=debug msg="completed challenge" Feb 17 19:51:06 volumio go-librespot[7986]: time="2026-02-17T19:51: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 17 19:51:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:51:07 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:07 volumio volumio[7653]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 19:51:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:10 volumio go-librespot[7993]: go-librespot daemon starting... Feb 17 19:51:10 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:10 volumio volumio[7653]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51:10+07:00" level=debug msg="app state loaded" Feb 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 19:51:10 volumio volumio[7653]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51: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 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51: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 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51: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 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51:10+07:00" level=info msg="zeroconf server listening on port 46605" Feb 17 19:51:10 volumio go-librespot[7994]: time="2026-02-17T19:51:10+07:00" level=debug msg="obtained new client token: AABNOvf5Kp543f27KRVc2Ny8xz0Rtx9DhdxHxWwCk9qjzU01S47pfB69GKvWRdTtKsJqja1bBIG1s1P67K4NQf1JpzrizqUrBgn38F/H/iB7QdyA9PmhK0Yw0mIHN+dPEWTrUQHTCiUUjxqHrW0hvJAo2r/2rTXaLRLPu3T83RLfoXlCQUEKBdQL1CHpCdEaAJvFv83pK3sFH5y2ZHyRaKGdtE+UDWctocxNQlX6qYr0Jwu/UAfmb54KeQ==" Feb 17 19:51:11 volumio go-librespot[7994]: time="2026-02-17T19:51:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:51:11 volumio go-librespot[7994]: time="2026-02-17T19:51:11+07:00" level=debug msg="completed keyexchange" Feb 17 19:51:11 volumio go-librespot[7994]: time="2026-02-17T19:51:11+07:00" level=debug msg="completed challenge" Feb 17 19:51:11 volumio go-librespot[7994]: time="2026-02-17T19:51: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 17 19:51:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:51:12 volumio volumio[7653]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 19:51:12 volumio volumio[7653]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 19:51:12 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:12 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:12 volumio volumio[7653]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 19:51:12 volumio volumio[7653]: info: MyVolumio login type: Token Feb 17 19:51:12 volumio volumio[7653]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 19:51:12 volumio volumio[7653]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 19:51:14 volumio volumio[7653]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 19:51:14 volumio volumio[7653]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 19:51:14 volumio volumio[7653]: info: Streaming services startup Feb 17 19:51:14 volumio volumio[7653]: info: Starting Streaming Daemon Feb 17 19:51:14 volumio sudo[8018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 19:51:14 volumio sudo[8018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:51:14 volumio volumio[7653]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 19:51:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 19:51:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:14 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:14 volumio volumio[7653]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:14 volumio go-librespot[8024]: go-librespot daemon starting... Feb 17 19:51:14 volumio sudo[8018]: pam_unix(sudo:session): session closed for user root Feb 17 19:51:14 volumio volumio[7653]: error: Cannot start Volumio Streaming Daemon Feb 17 19:51:14 volumio volumio[7653]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 19:51:14 volumio volumio[7653]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=debug msg="app state loaded" Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 19:51:14 volumio go-librespot[8025]: time="2026-02-17T19:51:14+07:00" level=info msg="zeroconf server listening on port 37793" Feb 17 19:51:15 volumio go-librespot[8025]: time="2026-02-17T19:51:15+07:00" level=debug msg="obtained new client token: AABN6ojD9n5DVoA0PGS0FPprsnuhtvq0n6VAYC/blJN/DsY5jEyPaCdiRNJh+vq4UXAb2vBuCwUin/o+tfFjjuDlWu1i0PmZg+N/JXMsa7cyGz/kPvJrWqLUdDwbkxeM9p+/l66htBoOxg3eE9IoTIR2FMD7SS6sFoNG3RHnLCp8PKzFEpqWB5pAVy1nBJUfk6BtdhnVBfZpWR0N9btNo98XrVYfRVff+UQ/7UmlPJRlA2YvcnM6Vlc=" Feb 17 19:51:15 volumio volumio[7653]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 17 19:51:15 volumio go-librespot[8025]: time="2026-02-17T19:51:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:51:15 volumio go-librespot[8025]: time="2026-02-17T19:51:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:51:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:51:15 volumio volumio[7653]: info: MyVolumio token set successfully Feb 17 19:51:15 volumio volumio[7653]: info: MYVOLUMIO: Adding device Feb 17 19:51:15 volumio volumio[7653]: info: MYVOLUMIO: Evaluating Server Feb 17 19:51:16 volumio volumio[7653]: info: MyVolumio status changed Feb 17 19:51:16 volumio volumio[7653]: info: Streaming services startup Feb 17 19:51:16 volumio volumio[7653]: info: Starting Streaming Daemon Feb 17 19:51:16 volumio volumio[7653]: info: Removing browser output: myVolumio user plan is not superstar Feb 17 19:51:16 volumio volumio[7653]: info: Removing audio output: Feb 17 19:51:16 volumio volumio[7653]: info: Stoppping Tunnel 1 Feb 17 19:51:16 volumio sudo[8053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 19:51:16 volumio sudo[8053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:51:16 volumio sudo[8053]: pam_unix(sudo:session): session closed for user root Feb 17 19:51:16 volumio sudo[8056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 17 19:51:16 volumio volumio[7653]: error: Cannot start Volumio Streaming Daemon Feb 17 19:51:16 volumio volumio[7653]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 19:51:16 volumio volumio[7653]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 19:51:16 volumio sudo[8056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:51:16 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 17 19:51:16 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 17 19:51:16 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 17 19:51:16 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 17 19:51:16 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 17 19:51:16 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 17 19:51:16 volumio sudo[8056]: pam_unix(sudo:session): session closed for user root Feb 17 19:51:16 volumio volumio[7653]: info: Remote SSH Stopped Feb 17 19:51:17 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:17 volumio volumio[7653]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:17 volumio volumio[7653]: info: Setting Geolocation for MyVolumio to as1 Feb 17 19:51:17 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:17 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:17 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:18 volumio volumio[7653]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 19:51:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 17 19:51:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:18 volumio go-librespot[8058]: go-librespot daemon starting... Feb 17 19:51:18 volumio go-librespot[8059]: time="2026-02-17T19:51:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:18 volumio go-librespot[8059]: time="2026-02-17T19:51:18+07:00" level=debug msg="app state loaded" Feb 17 19:51:18 volumio go-librespot[8059]: time="2026-02-17T19:51:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:18 volumio volumio[7653]: info: Updating MyVolumio device info Feb 17 19:51:18 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:18 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:18 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 19:51:19 volumio volumio[7653]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=info msg="zeroconf server listening on port 38431" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="obtained new client token: AAAvSrrwBkMM2q3cln4DJ9q6W6lbD9YkLKBeo9Db3TiKS0ZitNSWETk1vnVBksSDtIO8hFdYEFO0311DqOO6Z/3Kcx3bAO8EhM6VGVdX8fyuIxLO8t4TJHFb8tJhtdLwoa8mwxeVUSZfOekKJ6fs0LhYUu5foiS752F9+b/ff45DOam1iHcH9viAoVfpDMaQkWKjhQdTGwdoxmJLAq8sonAHNTCrwm3J6hTKsJ8FiYMMMfxXDZh4Rz09aQ==" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="completed keyexchange" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51:19+07:00" level=debug msg="completed challenge" Feb 17 19:51:19 volumio go-librespot[8059]: time="2026-02-17T19:51: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 17 19:51:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:51:20 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:20 volumio volumio[7653]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:22 volumio volumio[7653]: info: MYVOLUMIO: Adding device Feb 17 19:51:22 volumio volumio[7653]: info: MYVOLUMIO: Evaluating Server Feb 17 19:51:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 17 19:51:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:22 volumio go-librespot[8099]: go-librespot daemon starting... Feb 17 19:51:22 volumio go-librespot[8100]: time="2026-02-17T19:51:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:22 volumio go-librespot[8100]: time="2026-02-17T19:51:22+07:00" level=debug msg="app state loaded" Feb 17 19:51:22 volumio go-librespot[8100]: time="2026-02-17T19:51:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:22 volumio volumio[7653]: info: Setting Geolocation for MyVolumio to as1 Feb 17 19:51:22 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:22 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:22 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:23 volumio volumio[7653]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 19:51:23 volumio volumio[7653]: info: Initializing connection to go-librespot Websocket Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=info msg="zeroconf server listening on port 33685" Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=debug msg="new websocket client" Feb 17 19:51:23 volumio volumio[7653]: info: Connection to go-librespot Websocket established Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=debug msg="obtained new client token: AABz2AnIiNWOaBjsq017BYpF5e9wGNpcoi+1osJvbW3awVfvzTrC5WCv06ZI+n2AVgFGdZTyRhi8Go++5EmFaBUAvd7zKYk6r4ysU0iMack5cEl20AzqQRoteEVl9Eg8wfGiWvBjfJDG6Xq4KbpsF7qCJ+QOJjtmL33aL9g/Uv/EV0E7ly7dOcbzQolCVDrVCuvd1Xss9fZEQn4vJtc36wOIhuehuqx1Fl/1XoIhujnovK8+rKW+a1OGHQ==" Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 19:51:23 volumio go-librespot[8100]: time="2026-02-17T19:51:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:51:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 19:51:23 volumio volumio[7653]: info: Connection to go-librespot Websocket closed Feb 17 19:51:23 volumio volumio[7653]: info: Updating MyVolumio device info Feb 17 19:51:23 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:23 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:23 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:24 volumio volumio[7653]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 19:51:25 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 19:51:25 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 19:51:25 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 19:51:26 volumio volumio-remote-updater[25144]: Test mode disabled Feb 17 19:51:26 volumio volumio-remote-updater[25144]: Alpha mode disabled Feb 17 19:51:26 volumio volumio-remote-updater[25144]: Alpha legacy test mode disabled Feb 17 19:51:26 volumio volumio[7653]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 17 19:51:26 volumio volumio[7653]: info: Getting Spotify volume Feb 17 19:51:26 volumio volumio[7653]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 19:51:26 volumio volumio[7653]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 19:51:26 volumio volumio[7653]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 19:51:26 volumio volumio[7653]: errno: -111, Feb 17 19:51:26 volumio volumio[7653]: code: 'ECONNREFUSED', Feb 17 19:51:26 volumio volumio[7653]: syscall: 'connect', Feb 17 19:51:26 volumio volumio[7653]: address: '127.0.0.1', Feb 17 19:51:26 volumio volumio[7653]: port: 9879, Feb 17 19:51:26 volumio volumio[7653]: response: undefined Feb 17 19:51:26 volumio volumio[7653]: } Feb 17 19:51:26 volumio volumio[7653]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 19:51:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 17 19:51:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 19:51:27 volumio go-librespot[8123]: go-librespot daemon starting... Feb 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51:27+07:00" level=debug msg="app state loaded" Feb 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51: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 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51: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 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51: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 17 19:51:27 volumio go-librespot[8124]: time="2026-02-17T19:51:27+07:00" level=info msg="zeroconf server listening on port 34067" Feb 17 19:51:28 volumio sudo[8133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 19:50' Feb 17 19:51:28 volumio sudo[8133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 19:51:28 volumio go-librespot[8124]: time="2026-02-17T19:51:28+07:00" level=debug msg="obtained new client token: AACC0FvEkDYWUKesm9AXo1yzJr/xvb/YC/0E8ZlR11mmmhJu9bXg1KF1a9EusE0b9+CM6WbLtYNnvCbOVzzX5wj4pEUSYV4SY6QNOWMV6NcGMnZVcj0rQhEIjNs+XPoWnTfwTiu68spOsmJAaWM8QrySTI0wWja+OjQFDRLSea4T3km8NdHZ+HWvI52++lbSlyIQSGHnIrXGFMv8WMMuhoAL5LPcHK5T7ZVtPqio0l3F/1c4YZ5yfJ+fkQ==" Feb 17 19:51:28 volumio go-librespot[8124]: time="2026-02-17T19:51:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 19:51:28 volumio go-librespot[8124]: time="2026-02-17T19:51:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 19:51:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 19:51:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 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"