Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::resetVolumioState Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::getcurrentVolume Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:08:00 volumio volumio[1510]: Forking 3 albumart workers Feb 10 07:08:00 volumio sudo[1531]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 07:08:00 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:00 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:00 volumio sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 07:08:00 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:00 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:00 volumio volumio[1393]: info: Volumio Network Manager: Network status updated: 1 Feb 10 07:08:00 volumio volumio[1393]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::pushState Feb 10 07:08:00 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::updateTrackBlock Feb 10 07:08:00 volumio volumio[1393]: info: CorePlayQueue::getTrackBlock Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:08:00 volumio volumio-remote-updater[643]: [2026-02-10 07:08:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770682078 101 Feb 10 07:08:00 volumio volumio[1393]: 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 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:00 volumio volumio[1393]: info: Reloading queue from file Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::setRepeat null single undefined Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::pushState Feb 10 07:08:00 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::setRandom null Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::pushState Feb 10 07:08:00 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:00 volumio volumio[1393]: info: Setting Device type: Raspberry PI Feb 10 07:08:00 volumio volumio[1393]: info: Completed loading Core Plugins Feb 10 07:08:00 volumio volumio[1393]: info: Preparing to generate the ALSA configuration file Feb 10 07:08:00 volumio volumio[1393]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:08:00 volumio volumio[1393]: info: CoreStateMachine::pushState Feb 10 07:08:00 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:00 volumio volumio[1393]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:01 volumio volumio[1393]: info: Asound.conf file unchanged, so no further update is needed Feb 10 07:08:01 volumio volumio[1393]: info: Output device has changed, restarting MPD Feb 10 07:08:01 volumio sudo[1582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 07:08:01 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:01 volumio volumio[1393]: info: Output device has changed, restarting Shairport Sync Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:01 volumio sudo[1586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 07:08:01 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:01 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 10 07:08:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:01 volumio volumio[1393]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:08:01 volumio volumio[1393]: info: ___________ START PLUGINS ___________ Feb 10 07:08:01 volumio sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 07:08:01 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:01 volumio volumio[1393]: info: ControllerMpd::onStart: Initializing MPD Feb 10 07:08:01 volumio volumio[1393]: info: Creating MPD Configuration file Feb 10 07:08:01 volumio go-librespot[1598]: go-librespot daemon starting... Feb 10 07:08:01 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:01 volumio volumio[1393]: info: [1770682081332] CoreMusicLibrary::Adding element Media Servers Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 07:08:01 volumio go-librespot[1600]: time="2026-02-10T07:08:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:01 volumio go-librespot[1600]: time="2026-02-10T07:08:01+07:00" level=debug msg="app state loaded" Feb 10 07:08:01 volumio go-librespot[1600]: time="2026-02-10T07:08:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:01 volumio sudo[1609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 07:08:01 volumio sudo[1607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 07:08:01 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:01 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:01 volumio volumio[1393]: info: UPNP Browser: Client initialized successfully Feb 10 07:08:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 07:08:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 07:08:01 volumio systemd[1]: mpd.service: Consumed 7.048s CPU time. Feb 10 07:08:01 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 07:08:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 07:08:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 07:08:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 07:08:01 volumio volumio[1393]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 07:08:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 07:08:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 07:08:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 07:08:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 07:08:01 volumio volumio[1393]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:01 volumio volumio[1393]: info: [1770682081777] CoreMusicLibrary::Adding element Last_100 Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:01 volumio volumio[1393]: info: [1770682081806] CoreMusicLibrary::Adding element Webradio Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:01 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:08:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 07:08:01 volumio volumio[1393]: info: Initializing BBC Radios Feb 10 07:08:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 07:08:02 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:08:02 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:02 volumio volumio[1393]: info: Creating Spotify config file Feb 10 07:08:02 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+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 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+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 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+07:00" level=info msg="zeroconf server listening on port 38827" Feb 10 07:08:02 volumio sudo[1624]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 07:08:02 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 07:08:02 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+07:00" level=debug msg="obtained new client token: AAD9Dzs3fW2QxbtxVYzJzsY57QG56P81kEDFspImtb/C7ZIYFCMTKEbpKrEJBtTPabycA3FfmW4+sHnRJV3aOr1+gjqVKeptIbzCma+fk9hBKF2OjM5AvK0gRySEnfTR7wKYoxuDVm6y9j47QtK9PpnpTJONcdABbe2N6XR9SQ+UYMS9lts8ESyu3+0YMaHAr0BsdpAif27QfvK3iALcHXvw2vSNcQu7IUHd+XFe09d9U9hBDwIrtbCKyg==" Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08:02+07:00" level=debug msg="completed challenge" Feb 10 07:08:02 volumio go-librespot[1600]: time="2026-02-10T07:08: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 10 07:08:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:03 volumio volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:03 volumio volumio[1393]: info: [1770682083068] CoreMusicLibrary::Adding element YouTube Music Feb 10 07:08:03 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:03 volumio volumio[1393]: Cannot find translation for source YouTube Music Feb 10 07:08:03 volumio volumio[1393]: info: Volumio Calling Home Feb 10 07:08:03 volumio sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 10 07:08:03 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:03 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:03 volumio volumio[1534]: Starting albumart workers Feb 10 07:08:03 volumio volumio[1537]: Starting albumart workers Feb 10 07:08:03 volumio volumio[1393]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 07:08:03 volumio volumio[1393]: info: Discovery: Found device Volumio Feb 10 07:08:03 volumio volumio[1393]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:03 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:04 volumio volumio[1393]: info: MPD Permissions set Feb 10 07:08:04 volumio volumio[1393]: info: MPD Permissions set Feb 10 07:08:04 volumio volumio[1393]: info: Upmpdcli Daemon Started Feb 10 07:08:04 volumio volumio[1393]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 07:08:04 volumio volumio[1393]: info: Discovery: Found device Volumio Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:04 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:04 volumio volumio[1393]: info: Volumio called home Feb 10 07:08:04 volumio volumio[1393]: info: Spotify config file written Feb 10 07:08:04 volumio volumio[1532]: Starting albumart workers Feb 10 07:08:04 volumio sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 07:08:04 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:04 volumio go-librespot[1658]: go-librespot daemon starting... Feb 10 07:08:04 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:04 volumio go-librespot[1659]: time="2026-02-10T07:08:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:04 volumio go-librespot[1659]: time="2026-02-10T07:08:04+07:00" level=debug msg="app state loaded" Feb 10 07:08:04 volumio go-librespot[1659]: time="2026-02-10T07:08:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:04 volumio volumio[1393]: 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 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:04 volumio volumio[1393]: info: No need to fix Spotify hosts Feb 10 07:08:05 volumio go-librespot[1659]: time="2026-02-10T07:08:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:05 volumio go-librespot[1659]: time="2026-02-10T07:08:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:05 volumio go-librespot[1659]: time="2026-02-10T07:08:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:05 volumio go-librespot[1659]: time="2026-02-10T07:08:05+07:00" level=info msg="zeroconf server listening on port 43955" Feb 10 07:08:05 volumio go-librespot[1659]: time="2026-02-10T07:08:05+07:00" level=debug msg="obtained new client token: AACUy47vfxSiOegdszR+UEaWRZv5c4p7/OL8dgoEG8fm2V3FCWmEVXmQCVN9O3glZUfu3jI932dEeVRpC4OLXjeZmvgHr+d36FGdYJd9ouPzcucBB6LAQblG/YSa1YIoLJ1/gFn850AMGQSZX2LqzpZsDA2W9XY/IRJzNzogUH1hz2iyAaw5b9XTJ+/6vRL18f2EbAhWio5ppjqx4o0iPch2ba7PRMiUud+yTk8sowhftlWVui7O02hleg==" Feb 10 07:08:05 volumio go-librespot[1659]: time="2026-02-10T07:08:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:06 volumio go-librespot[1659]: time="2026-02-10T07:08:06+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:06 volumio go-librespot[1659]: time="2026-02-10T07:08:06+07:00" level=debug msg="completed challenge" Feb 10 07:08:06 volumio go-librespot[1659]: time="2026-02-10T07:08: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 10 07:08:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:06 volumio volumio[1393]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 07:08:06 volumio volumio[1393]: SPOTIFY: BQDzE3j5hLfoE8tZv4qllEtU6mw2NrVIELHW0PznI1yfT2frNZkVXWT7BEZPIZKM5zMEJBBqimDl4c9Vw5g8-QgotEsoE9T_7GSLxx9yVaAy7xE0Dm4aJvUZOwWiIgBH_P92VvDAm_Q-oDmox7kWpJ-VYfEjfDOtVH7nuqCMH93IO_Wo20ezVi6pvjoDTP1cbOth3FRC8DNhC2NkrmGcGA_ZIDOh7dtpsJBSGWyY6K8kw2JQI-asdHKIFhBmiaEYyUVkI2bbl879lz_ajN6FOIJzFlue-AUAZpprRZxHFqJrigOVb0hVDVXV Feb 10 07:08:06 volumio volumio[1393]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 07:08:06 volumio volumio[1393]: info: New Spotify access token = BQDzE3j5hLfoE8tZv4qllEtU6mw2NrVIELHW0PznI1yfT2frNZkVXWT7BEZPIZKM5zMEJBBqimDl4c9Vw5g8-QgotEsoE9T_7GSLxx9yVaAy7xE0Dm4aJvUZOwWiIgBH_P92VvDAm_Q-oDmox7kWpJ-VYfEjfDOtVH7nuqCMH93IO_Wo20ezVi6pvjoDTP1cbOth3FRC8DNhC2NkrmGcGA_ZIDOh7dtpsJBSGWyY6K8kw2JQI-asdHKIFhBmiaEYyUVkI2bbl879lz_ajN6FOIJzFlue-AUAZpprRZxHFqJrigOVb0hVDVXV Feb 10 07:08:06 volumio volumio[1393]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 07:08:06 volumio volumio[1393]: info: Starting Shairport Sync Feb 10 07:08:06 volumio volumio[1393]: info: Starting Shairport Sync Feb 10 07:08:06 volumio volumio[1393]: info: Starting Shairport Sync Feb 10 07:08:06 volumio sudo[1693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:08:06 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:06 volumio sudo[1700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:08:06 volumio sudo[1700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:06 volumio sudo[1703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:08:06 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 07:08:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 07:08:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:08:06 volumio systemd[1]: shairport-sync.service: Consumed 2.257s CPU time. Feb 10 07:08:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:08:06 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 07:08:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 07:08:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:08:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:08:06 volumio volumio[1393]: info: Shairport-Sync Started Feb 10 07:08:06 volumio volumio[1393]: Error adding Membership: Error: addMembership EINVAL Feb 10 07:08:06 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:06 volumio sudo[1700]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:06 volumio volumio[1393]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:06 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:07 volumio volumio[1393]: info: Shairport-Sync Started Feb 10 07:08:07 volumio volumio[1393]: info: Shairport-Sync Started Feb 10 07:08:07 volumio volumio[1393]: 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 10 07:08:07 volumio volumio[1393]: info: Spotify Successfully logged in Feb 10 07:08:07 volumio volumio[1393]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:07 volumio volumio[1393]: info: [1770682087149] CoreMusicLibrary::Adding element Spotify Feb 10 07:08:07 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:07 volumio volumio[1393]: Cannot find translation for source YouTube Music Feb 10 07:08:07 volumio volumio[1393]: Cannot find translation for source Spotify Feb 10 07:08:07 volumio volumio[1393]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 10 07:08:07 volumio volumio[1393]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:08:08 volumio volumio[1393]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:08:08 volumio volumio[1393]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:08 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:08 volumio volumio[1393]: info: CoreStateMachine::pushState Feb 10 07:08:08 volumio volumio[1393]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:08 volumio volumio[1393]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:08 volumio volumio[1393]: info: go-librespot daemon successfully initialized Feb 10 07:08:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 10 07:08:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:09 volumio mpd[1639]: 2026-02-10T07:08:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 07:08:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:09 volumio go-librespot[1739]: go-librespot daemon starting... Feb 10 07:08:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 07:08:09 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:09 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+07:00" level=debug msg="app state loaded" Feb 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:09 volumio volumio[1393]: error: MPD error: The expression evaluated to a falsy value: Feb 10 07:08:09 volumio volumio[1393]: assert.ok(self.idling) Feb 10 07:08:09 volumio volumio[1393]: error: The expression evaluated to a falsy value: Feb 10 07:08:09 volumio volumio[1393]: assert.ok(self.idling) Feb 10 07:08:09 volumio volumio[1393]: info: Completed starting Core Plugins Feb 10 07:08:09 volumio volumio[1393]: info: ------------------------------------------- Feb 10 07:08:09 volumio volumio[1393]: info: ----- MyVolumio plugins startup ---- Feb 10 07:08:09 volumio volumio[1393]: info: ------------------------------------------- Feb 10 07:08:09 volumio volumio[1393]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 07:08:09 volumio volumio[1393]: info: MPD running with PID1639 Feb 10 07:08:09 volumio volumio[1393]: ,establishing connection Feb 10 07:08:09 volumio volumio[1393]: error: updateQueue error: null Feb 10 07:08:09 volumio volumio[1393]: error: updateQueue error: null Feb 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+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 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+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 10 07:08:09 volumio go-librespot[1742]: time="2026-02-10T07:08:09+07:00" level=info msg="zeroconf server listening on port 41321" Feb 10 07:08:10 volumio go-librespot[1742]: time="2026-02-10T07:08:10+07:00" level=debug msg="obtained new client token: AACTz/pXZ5q+ME0bVxSlQrycRYhm8Gz5eaoZDCPT/6xzmUH/yPwQwAGC5WT9rTqi8FapJ+j7YrRfFMp13m5AS4W9BOurJiJ+IF8SBGMmaLnzOSdT6oicFLV2Z3MsbyMIzgqKAt7fW9Q0haLLqgl3lHZt5H+w0tRnEKpGLnV8G1TsO1cgVurRMHqVkwASoApLjNGRBGKaHLqIBWXu9sNkQsB8xH3rP2ZgoLkLXJl41WUSXKAIsr20m68=" Feb 10 07:08:10 volumio go-librespot[1742]: time="2026-02-10T07:08:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:10 volumio go-librespot[1742]: time="2026-02-10T07:08:10+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:10 volumio go-librespot[1742]: time="2026-02-10T07:08:10+07:00" level=debug msg="completed challenge" Feb 10 07:08:10 volumio go-librespot[1742]: time="2026-02-10T07:08:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:11 volumio volumio[1393]: info: Initializing connection to go-librespot Websocket Feb 10 07:08:11 volumio volumio[1393]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:08:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 10 07:08:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:13 volumio go-librespot[1753]: go-librespot daemon starting... Feb 10 07:08:13 volumio go-librespot[1754]: time="2026-02-10T07:08:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:13 volumio go-librespot[1754]: time="2026-02-10T07:08:13+07:00" level=debug msg="app state loaded" Feb 10 07:08:13 volumio go-librespot[1754]: time="2026-02-10T07:08:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=info msg="zeroconf server listening on port 39785" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="obtained new client token: AABkFtON/1Zpj/uE2MGqJb2IBJgQBqZGdYP7GrbQiNKUzak14Il26OSctZ5u8ub3s7pBq8fZkARwwXovNiogA0+pWRSOiiKsOAUUdf9dGILH+edd7fBFAKU/tzWOUoIHwQN4v3DPAZr8phT5tH6e71DHqwkxw0uCCbe9pMNxf9f6koLXxVFfN4SP9KtwcdrUckOLaLqIqC/7TuVYUPEHWbt4IesKoEDb3h8JSm6TWOpnHcKtXxv8ChsbzQ==" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:14 volumio volumio[1393]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=debug msg="completed challenge" Feb 10 07:08:14 volumio go-librespot[1754]: time="2026-02-10T07:08:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:14 volumio volumio[1393]: info: Initializing connection to go-librespot Websocket Feb 10 07:08:14 volumio volumio[1393]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:08:17 volumio volumio[1393]: info: Initializing connection to go-librespot Websocket Feb 10 07:08:17 volumio volumio[1393]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:08:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 10 07:08:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:17 volumio go-librespot[1762]: go-librespot daemon starting... Feb 10 07:08:17 volumio go-librespot[1763]: time="2026-02-10T07:08:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:17 volumio go-librespot[1763]: time="2026-02-10T07:08:17+07:00" level=debug msg="app state loaded" Feb 10 07:08:17 volumio go-librespot[1763]: time="2026-02-10T07:08:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+07:00" level=info msg="zeroconf server listening on port 35291" Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin bluetooth to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin multiroom to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin metavolumio to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin cd_controller to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 10 07:08:18 volumio volumio[1393]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+07:00" level=debug msg="obtained new client token: AACX7QgwbBZ/Eg7sNSZTWrmGGCQn2NP9FqnvcofUHuos1KaOpLIW6OydBROb4u3yelBudDymx4m7yfnaoLBd6pt8sMkPCRZb/6JhsX7vbW3VBzZnEy8q5aLuptZVpM8bQSAk/qVuHwDe717qaolxnxdbj1+fodTwGVY5tZE18WiYTwbjY8AyKHQh9/ea6EAbfxYtvqVC/6A2P5jJT65ss8hHlhc22bb6iOHNCuZ/77+fMtHhw3zAYknfRg==" Feb 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+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 10 07:08:18 volumio go-librespot[1763]: time="2026-02-10T07:08:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 10 07:08:19 volumio go-librespot[1763]: time="2026-02-10T07:08:19+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:19 volumio go-librespot[1763]: time="2026-02-10T07:08:19+07:00" level=debug msg="completed challenge" Feb 10 07:08:19 volumio go-librespot[1763]: time="2026-02-10T07:08: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 10 07:08:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:20 volumio volumio[1393]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 10 07:08:20 volumio volumio[1393]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 10 07:08:20 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:20 volumio volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:20 volumio volumio[1393]: info: Starting MyVolumio Remote Streaming Endpoints Feb 10 07:08:20 volumio volumio[1393]: info: MyVolumio login type: Token Feb 10 07:08:20 volumio volumio[1393]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 10 07:08:20 volumio volumio[1393]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 10 07:08:22 volumio volumio[1393]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 10 07:08:22 volumio volumio[1393]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 10 07:08:22 volumio volumio[1393]: info: Streaming services startup Feb 10 07:08:22 volumio volumio[1393]: info: Starting Streaming Daemon Feb 10 07:08:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 10 07:08:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:22 volumio volumio[1393]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 10 07:08:22 volumio sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 07:08:22 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:22 volumio go-librespot[1790]: go-librespot daemon starting... Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=debug msg="app state loaded" Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:22 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:22 volumio volumio[1393]: info: Initializing connection to go-librespot Websocket Feb 10 07:08:22 volumio volumio[1393]: error: Cannot start Volumio Streaming Daemon Feb 10 07:08:22 volumio volumio[1393]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 07:08:22 volumio volumio[1393]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=debug msg="new websocket client" Feb 10 07:08:22 volumio volumio[1393]: info: Connection to go-librespot Websocket established Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:22 volumio go-librespot[1793]: time="2026-02-10T07:08:22+07:00" level=info msg="zeroconf server listening on port 45689" Feb 10 07:08:23 volumio go-librespot[1793]: time="2026-02-10T07:08:23+07:00" level=debug msg="obtained new client token: AABnqgJc7DQjRUUhbY5m4ftVa/rKbpAm9atSnZVFlOYw8inpVx67U6rgp2K9zzvL3Y5u7JKxgFy6EV+k7Dbi92nvXbqwDQsF8+EYYOeHJeFCGZ3vjQiHaK4vcPdxQz3aUsAmHrChKEbBB/I/QQlkprh3J94V+/IwbqmeqQtBWym8rSoaIKK3XE/U2ytmwgqiIkMS7trchX1+bjywCVb5pdVK/b8FqDlZdFv0qgMpjRFZIGAHEnnVtN8=" Feb 10 07:08:23 volumio go-librespot[1793]: time="2026-02-10T07:08:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:23 volumio go-librespot[1793]: time="2026-02-10T07:08:23+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:23 volumio go-librespot[1793]: time="2026-02-10T07:08:23+07:00" level=debug msg="completed challenge" Feb 10 07:08:23 volumio go-librespot[1793]: time="2026-02-10T07:08:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:23 volumio volumio[1393]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 10 07:08:23 volumio volumio[1393]: info: Connection to go-librespot Websocket closed Feb 10 07:08:24 volumio volumio[1393]: info: MyVolumio token set successfully Feb 10 07:08:24 volumio volumio[1393]: info: MYVOLUMIO: Adding device Feb 10 07:08:24 volumio volumio[1393]: info: MYVOLUMIO: Evaluating Server Feb 10 07:08:24 volumio volumio[1393]: info: MyVolumio status changed Feb 10 07:08:24 volumio volumio[1393]: info: Streaming services startup Feb 10 07:08:24 volumio volumio[1393]: info: Starting Streaming Daemon Feb 10 07:08:24 volumio volumio[1393]: info: Removing browser output: myVolumio user plan is not superstar Feb 10 07:08:24 volumio volumio[1393]: info: Removing audio output: Feb 10 07:08:24 volumio volumio[1393]: info: Stoppping Tunnel 1 Feb 10 07:08:24 volumio sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 07:08:24 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:24 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:24 volumio volumio[1393]: error: Cannot start Volumio Streaming Daemon Feb 10 07:08:24 volumio sudo[1827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 10 07:08:24 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:24 volumio volumio[1393]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 07:08:24 volumio volumio[1393]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 07:08:24 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 10 07:08:24 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 10 07:08:24 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 10 07:08:24 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 10 07:08:24 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 10 07:08:24 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 10 07:08:24 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:24 volumio volumio[1393]: info: Remote SSH Stopped Feb 10 07:08:25 volumio volumio[1393]: info: Getting Spotify volume Feb 10 07:08:25 volumio volumio[1393]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 07:08:25 volumio volumio[1393]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:08:25 volumio volumio[1393]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 10 07:08:25 volumio volumio[1393]: errno: -111, Feb 10 07:08:25 volumio volumio[1393]: code: 'ECONNREFUSED', Feb 10 07:08:25 volumio volumio[1393]: syscall: 'connect', Feb 10 07:08:25 volumio volumio[1393]: address: '127.0.0.1', Feb 10 07:08:25 volumio volumio[1393]: port: 9879, Feb 10 07:08:25 volumio volumio[1393]: response: undefined Feb 10 07:08:25 volumio volumio[1393]: } Feb 10 07:08:25 volumio volumio[1393]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 07:08:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 10 07:08:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:26 volumio go-librespot[1846]: go-librespot daemon starting... Feb 10 07:08:26 volumio go-librespot[1847]: time="2026-02-10T07:08:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:26 volumio go-librespot[1847]: time="2026-02-10T07:08:26+07:00" level=debug msg="app state loaded" Feb 10 07:08:26 volumio go-librespot[1847]: time="2026-02-10T07:08:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:27 volumio sudo[1857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 07:07' Feb 10 07:08:27 volumio sudo[1857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=info msg="zeroconf server listening on port 40395" Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="obtained new client token: AABvCG7CnXhM5H3hVVwBvouo1Zf6K/syM/zXolnlD2QbBNE1h3Ci/zjxcRDQe17RNG8nFK9KbLhuUz4IJJkWyl/T4SDXWxCRgw8OfoReNvRJc5jpcvkY7OjcIDVyphJ/14Qk76syydBB83cvdaq5oNrByLTxC2M4N58zht0GISTjUEXAMBsxS3hXBqIzxRSqhbBKqyapOSUypp0a8Kk9FPDORa2DJIEYln5tD7pDPQPywolcCoWUfH7j6g==" Feb 10 07:08:27 volumio sudo[1857]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:27 volumio volumio-remote-updater[643]: [2026-02-10 07:08:27] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 10 07:08:27 volumio volumio-remote-updater[643]: [2026-02-10 07:08:27] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=debug msg="completed challenge" Feb 10 07:08:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 10 07:08:27 volumio systemd[1]: volumio.service: Consumed 56.649s CPU time. Feb 10 07:08:27 volumio go-librespot[1847]: time="2026-02-10T07:08:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:27 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 07:08:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:27 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 07:08:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9881. Feb 10 07:08:27 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 07:08:27 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 10 07:08:27 volumio systemd[1]: volumio.service: Consumed 56.649s CPU time. Feb 10 07:08:27 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 10 07:08:27 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 07:08:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 10 07:08:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:30 volumio go-librespot[1892]: go-librespot daemon starting... Feb 10 07:08:30 volumio go-librespot[1893]: time="2026-02-10T07:08:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:30 volumio go-librespot[1893]: time="2026-02-10T07:08:30+07:00" level=debug msg="app state loaded" Feb 10 07:08:30 volumio go-librespot[1893]: time="2026-02-10T07:08:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:31 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:31 volumio volumio[1876]: info: ----- Volumio3 ---- Feb 10 07:08:31 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:31 volumio volumio[1876]: info: ----- System startup ---- Feb 10 07:08:31 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=info msg="zeroconf server listening on port 44959" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="obtained new client token: AABSWRA+WnNZFxJMD10xU3gdqizZuzA0qY94HnWzjP8n//8muxXmfaheZn3VxPn/I8vwgJWWWv39CRfVwPjxFkmz5EiOmI90Sv+YmZEZ6+VTaUdl+WU4qo8GgsbmxYx++mZyOIEjUPXMw5XdC++jshcg7OahXz8BYH6pKCKnVqs47fMzSl1d5n29RaFFEISh/frXh5A5bmSy4R7FMuCG9fOkuXqKPiHlrxn7TWXB6k/7CQv+8r54GeFV4Q==" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:31 volumio go-librespot[1893]: time="2026-02-10T07:08:31+07:00" level=debug msg="completed challenge" Feb 10 07:08:32 volumio go-librespot[1893]: time="2026-02-10T07:08:32+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:32 volumio volumio-remote-updater[643]: [2026-02-10 07:08:32] [connect] Successful connection Feb 10 07:08:32 volumio volumio[1876]: info: MYVOLUMIO Environment detected Feb 10 07:08:32 volumio volumio[1876]: info: Plugin folders cleanup Feb 10 07:08:32 volumio volumio[1876]: info: Scanning into folder /volumio/app/plugins/ Feb 10 07:08:32 volumio volumio[1876]: info: Scanning category audio_interface Feb 10 07:08:32 volumio volumio[1876]: info: Scanning category miscellanea Feb 10 07:08:32 volumio volumio[1876]: info: Scanning category music_service Feb 10 07:08:33 volumio volumio[1876]: info: Scanning category plugins.json Feb 10 07:08:33 volumio volumio[1876]: info: Scanning category system_controller Feb 10 07:08:33 volumio volumio[1876]: info: Scanning category user_interface Feb 10 07:08:33 volumio volumio[1876]: info: Scanning into folder /data/plugins/ Feb 10 07:08:33 volumio volumio[1876]: info: Scanning category music_service Feb 10 07:08:33 volumio volumio[1876]: info: Plugin folders cleanup completed Feb 10 07:08:33 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:33 volumio volumio[1876]: info: ----- Core plugins startup ---- Feb 10 07:08:33 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:33 volumio volumio[1876]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 07:08:33 volumio volumio[1876]: info: Adding plugin upnp to MyMusic Plugins Feb 10 07:08:33 volumio volumio[1876]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 07:08:33 volumio volumio[1876]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 07:08:33 volumio volumio[1876]: info: Loading plugins from folder /data/plugins/ Feb 10 07:08:33 volumio volumio[1876]: info: Loading plugin "system"... Feb 10 07:08:33 volumio volumio[1876]: info: Loading plugin "appearance"... Feb 10 07:08:34 volumio volumio[1876]: info: Loading plugin "network"... Feb 10 07:08:34 volumio volumio[1876]: info: Refreshing Cached IP Addresses Feb 10 07:08:35 volumio sudo[1915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 07:08:35 volumio sudo[1915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:35 volumio sudo[1917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 07:08:35 volumio sudo[1915]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "services"... Feb 10 07:08:35 volumio sudo[1917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "alsa_controller"... Feb 10 07:08:35 volumio sudo[1917]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:35 volumio sudo[1927]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 07:08:35 volumio sudo[1927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:35 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "wizard"... Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "networkfs"... Feb 10 07:08:35 volumio volumio[1876]: info: Starting Udev Watcher for removable devices Feb 10 07:08:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 10 07:08:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:35 volumio volumio[1876]: info: Ignoring mount for partition: boot Feb 10 07:08:35 volumio volumio[1876]: info: Ignoring mount for partition: volumio Feb 10 07:08:35 volumio volumio[1876]: info: Ignoring mount for partition: volumio_data Feb 10 07:08:35 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "volumio_command_line_client"... Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "upnp"... Feb 10 07:08:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:35 volumio volumio[1876]: info: [1770682115380] Starting Upmpd Daemon Feb 10 07:08:35 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 07:08:35 volumio go-librespot[1948]: go-librespot daemon starting... Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "my_music"... Feb 10 07:08:35 volumio volumio[1876]: info: Loading plugin "mpd"... Feb 10 07:08:35 volumio go-librespot[1949]: time="2026-02-10T07:08:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:35 volumio go-librespot[1949]: time="2026-02-10T07:08:35+07:00" level=debug msg="app state loaded" Feb 10 07:08:35 volumio go-librespot[1949]: time="2026-02-10T07:08:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:35 volumio go-librespot[1949]: time="2026-02-10T07:08:35+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:35 volumio go-librespot[1949]: time="2026-02-10T07:08:35+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 10 07:08:35 volumio go-librespot[1949]: time="2026-02-10T07:08:35+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 10 07:08:36 volumio go-librespot[1949]: time="2026-02-10T07:08:36+07:00" level=info msg="zeroconf server listening on port 46381" Feb 10 07:08:36 volumio volumio[1876]: info: Loading plugin "upnp_browser"... Feb 10 07:08:36 volumio go-librespot[1949]: time="2026-02-10T07:08:36+07:00" level=debug msg="obtained new client token: AAAt8hKUjhh4PF788EgOEQA9KCd+ok/VpVI0RaaIpMkUntJjqnrCzOm7Vca3RvghPQxNyVe0KnqJvpuv80xF0t01JV4/N+btZ6MRKYdDw2UMwryfzPxU80ELbQsVWRCe1lsZYa2dTLRcjvv2FmUnL4cVoqrVDs71bDqyV/mKGKWQw9+z4kWcUVJf8RIvkc+QR8AurfFecNhR+WzwXHJU/MBDqMpMJKUVQ1jBvwkhKx8D9ovzOHdOTVM=" Feb 10 07:08:36 volumio go-librespot[1949]: time="2026-02-10T07:08:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:36 volumio go-librespot[1949]: time="2026-02-10T07:08:36+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:36 volumio go-librespot[1949]: time="2026-02-10T07:08:36+07:00" level=debug msg="completed challenge" Feb 10 07:08:36 volumio go-librespot[1949]: time="2026-02-10T07:08:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:37 volumio sudo[1927]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:38 volumio volumio[1876]: info: Starting UPNP Browser Feb 10 07:08:38 volumio volumio[1876]: info: Loading plugin "alarm-clock"... Feb 10 07:08:39 volumio volumio[1876]: info: Loading plugin "airplay_emulation"... Feb 10 07:08:39 volumio volumio[1876]: info: Starting Shairport Sync Feb 10 07:08:39 volumio volumio[1876]: info: Loading plugin "last_100"... Feb 10 07:08:39 volumio volumio[1876]: info: Loading plugin "webradio"... Feb 10 07:08:39 volumio volumio[1876]: info: Loading plugin "i2s_dacs"... Feb 10 07:08:39 volumio volumio[1876]: info: Loading plugin "volumiodiscovery"... Feb 10 07:08:39 volumio volumio[1876]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 07:08:39 volumio volumio[1876]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:08:39 volumio volumio[1876]: *** WARNING *** For more information see Feb 10 07:08:39 volumio volumio[1876]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 07:08:39 volumio volumio[1876]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:08:39 volumio volumio[1876]: *** WARNING *** For more information see Feb 10 07:08:39 volumio node[1876]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 07:08:39 volumio node[1876]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:08:39 volumio node[1876]: *** WARNING *** For more information see Feb 10 07:08:39 volumio node[1876]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 07:08:39 volumio node[1876]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:08:39 volumio node[1876]: *** WARNING *** For more information see Feb 10 07:08:39 volumio volumio[1876]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 07:08:39 volumio volumio[1876]: info: Discovery: Started advertising with name: Volumio Feb 10 07:08:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 10 07:08:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:39 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 07:08:39 volumio volumio[1876]: info: Loading plugin "spop"... Feb 10 07:08:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:39 volumio go-librespot[1958]: go-librespot daemon starting... Feb 10 07:08:39 volumio go-librespot[1959]: time="2026-02-10T07:08:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:39 volumio go-librespot[1959]: time="2026-02-10T07:08:39+07:00" level=debug msg="app state loaded" Feb 10 07:08:39 volumio go-librespot[1959]: time="2026-02-10T07:08:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+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 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+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 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=info msg="zeroconf server listening on port 41539" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=debug msg="obtained new client token: AACCjo2mBQpX560DvTw0qK1YtF5W609xHx6X0JW7F7CFnF/R5glwq4xMqicqYRpIkfn57oluJHiQ7Q+RZzu/3KWC0g6WlK56akEnRvInBp6Kci7+IiJ1SHohLC+Vcpnttf6HD49131x4svYxJPjmSitmx6sQMuuruMSCEd7ObiS58EZO3J6MFfKj0gLmZ8CNmzs3PbU/iQ5zbI2FoavnZl9fWMyvE1Inr0uLf4rQqlVhuwokVhUrQiXAWw==" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=debug msg="completed challenge" Feb 10 07:08:40 volumio go-librespot[1959]: time="2026-02-10T07:08:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:41 volumio volumio[1876]: info: Loading plugin "ytcr"... Feb 10 07:08:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 10 07:08:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:44 volumio go-librespot[1968]: go-librespot daemon starting... Feb 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+07:00" level=debug msg="app state loaded" Feb 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:44 volumio volumio[1876]: info: Loading plugin "ytmusic"... Feb 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+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 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+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 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+07:00" level=info msg="zeroconf server listening on port 35043" Feb 10 07:08:44 volumio go-librespot[1969]: time="2026-02-10T07:08:44+07:00" level=debug msg="obtained new client token: AAD17wJS1uZnFQ26xSdNh+8bbGYW9DkIBrZTIEtuPnlCSA2TrFZuyhJD9derXFzMu+4kU/k7Sb6QMWF0QmApzQR8Ah2xHFgbbKAdmls9qTSAvY+AuP2BvWw59GwXzGY8hOD4hjuwbf/nx+Bnu/f9UZBNiIXpLo4J6PttN1EDbt2YLnzTcI0mlxiqcahIoOYzPp9X4iVOKKMdExpcCOYH9d/A+No5FOuCPFtoyB0kxosN8wicY55H45wnKA==" Feb 10 07:08:45 volumio go-librespot[1969]: time="2026-02-10T07:08:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:45 volumio volumio-remote-updater[643]: [2026-02-10 07:08:45] [connect] Successful connection Feb 10 07:08:45 volumio go-librespot[1969]: time="2026-02-10T07:08:45+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:45 volumio go-librespot[1969]: time="2026-02-10T07:08:45+07:00" level=debug msg="completed challenge" Feb 10 07:08:45 volumio go-librespot[1969]: time="2026-02-10T07:08:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "outputs"... Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "albumart"... Feb 10 07:08:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:45 volumio volumio[1876]: info: Plugin example_plugin is not enabled Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "inputs"... Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "updater_comm"... Feb 10 07:08:45 volumio volumio[1876]: info: Plugin mpdemulation is not enabled Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "rest_api"... Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "websocket"... Feb 10 07:08:45 volumio volumio[1876]: info: Starting Socket.io Server version 1.7.4 Feb 10 07:08:45 volumio volumio[1876]: info: Loading plugin "RoonBridge"... Feb 10 07:08:46 volumio volumio[1876]: info: Applying required configuration parameters for plugin RoonBridge Feb 10 07:08:46 volumio volumio[1876]: info: Loading i18n strings for locale en Feb 10 07:08:46 volumio volumio[1876]: Updating browse sources language Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::initPlayerControls Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:08:46 volumio volumio[1876]: Express server listening on port 3000 Feb 10 07:08:46 volumio volumio[1876]: [Metrics] WebUI: 15s 971.44ms Feb 10 07:08:46 volumio volumio[1990]: Forking 3 albumart workers Feb 10 07:08:46 volumio volumio[1876]: info: CoreStateMachine::resetVolumioState Feb 10 07:08:46 volumio volumio[1876]: info: CoreStateMachine::getcurrentVolume Feb 10 07:08:46 volumio volumio[1876]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:08:46 volumio sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 07:08:46 volumio sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 07:08:46 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:46 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:46 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:46 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:46 volumio volumio[1876]: info: Volumio Network Manager: Network status updated: 1 Feb 10 07:08:47 volumio volumio[1876]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::pushState Feb 10 07:08:47 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::updateTrackBlock Feb 10 07:08:47 volumio volumio[1876]: info: CorePlayQueue::getTrackBlock Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:08:47 volumio volumio-remote-updater[643]: [2026-02-10 07:08:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770682125 101 Feb 10 07:08:47 volumio volumio[1876]: 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 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:47 volumio volumio[1876]: info: Reloading queue from file Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::setRepeat null single undefined Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::pushState Feb 10 07:08:47 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::setRandom null Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::pushState Feb 10 07:08:47 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:47 volumio volumio[1876]: info: Setting Device type: Raspberry PI Feb 10 07:08:47 volumio volumio[1876]: info: Completed loading Core Plugins Feb 10 07:08:47 volumio volumio[1876]: info: Preparing to generate the ALSA configuration file Feb 10 07:08:47 volumio volumio[1876]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:08:47 volumio volumio[1876]: info: CoreStateMachine::pushState Feb 10 07:08:47 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:47 volumio volumio[1876]: info: Asound.conf file unchanged, so no further update is needed Feb 10 07:08:47 volumio volumio[1876]: info: Output device has changed, restarting MPD Feb 10 07:08:47 volumio volumio[1876]: info: Output device has changed, restarting Shairport Sync Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:47 volumio sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 07:08:47 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:47 volumio sudo[2052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 07:08:47 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:47 volumio volumio[1876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:08:47 volumio sudo[2054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 07:08:47 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:47 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:47 volumio volumio[1876]: info: ___________ START PLUGINS ___________ Feb 10 07:08:47 volumio volumio[1876]: info: ControllerMpd::onStart: Initializing MPD Feb 10 07:08:47 volumio volumio[1876]: info: Creating MPD Configuration file Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:47 volumio volumio[1876]: info: [1770682127787] CoreMusicLibrary::Adding element Media Servers Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:47 volumio sudo[2063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 07:08:47 volumio sudo[2063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:47 volumio volumio[1876]: info: UPNP Browser: Client initialized successfully Feb 10 07:08:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 07:08:47 volumio sudo[2065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 07:08:47 volumio sudo[2065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:47 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:47 volumio sudo[2063]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:47 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:47 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 07:08:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 07:08:47 volumio systemd[1]: mpd.service: Consumed 7.217s CPU time. Feb 10 07:08:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 07:08:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 07:08:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 07:08:48 volumio volumio[1876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 07:08:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 07:08:48 volumio volumio[1876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:48 volumio volumio[1876]: info: [1770682128158] CoreMusicLibrary::Adding element Last_100 Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:48 volumio volumio[1876]: info: [1770682128172] CoreMusicLibrary::Adding element Webradio Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:08:48 volumio volumio[1876]: info: Initializing BBC Radios Feb 10 07:08:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 10 07:08:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:48 volumio go-librespot[2088]: go-librespot daemon starting... Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:48 volumio go-librespot[2092]: time="2026-02-10T07:08:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:48 volumio go-librespot[2092]: time="2026-02-10T07:08:48+07:00" level=debug msg="app state loaded" Feb 10 07:08:48 volumio go-librespot[2092]: time="2026-02-10T07:08:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:48 volumio volumio[1876]: info: Creating Spotify config file Feb 10 07:08:48 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:48 volumio sudo[2075]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 07:08:48 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 07:08:48 volumio sudo[2075]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:49 volumio go-librespot[2092]: time="2026-02-10T07:08:49+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:49 volumio go-librespot[2092]: time="2026-02-10T07:08:49+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:08:49 volumio go-librespot[2092]: time="2026-02-10T07:08:49+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:08:49 volumio go-librespot[2092]: time="2026-02-10T07:08:49+07:00" level=info msg="zeroconf server listening on port 33085" Feb 10 07:08:49 volumio go-librespot[2092]: time="2026-02-10T07:08:49+07:00" level=debug msg="obtained new client token: AABSa7FAfVAoPPY2+pOVCsZs+dULpZkQmpSEp351xtC+KX8kmRdaOqr5x/vBehK6AxzExoby0oLWQSvnt0+kw2scIi7cEWaEcS3jpAU/sTKsNS/gv2jGBqO8j4pYpXWt4ZsKya2993YOGNFLUTKTVwm5n4LfVPBDFP6moejio2AfDEU3gG1Yqe7VcOcTmdCDXF546Q9f9vPN8QMzNtwz6CiaN+JvCJb+w0vjBz2jeUEq9wXx1mPp3xv7Zw==" Feb 10 07:08:49 volumio go-librespot[2092]: time="2026-02-10T07:08:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:49 volumio volumio[1876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:49 volumio volumio[1876]: info: [1770682129724] CoreMusicLibrary::Adding element YouTube Music Feb 10 07:08:49 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:49 volumio volumio[1876]: Cannot find translation for source YouTube Music Feb 10 07:08:49 volumio volumio[1876]: info: Volumio Calling Home Feb 10 07:08:49 volumio sudo[2103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 10 07:08:49 volumio sudo[2103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:49 volumio sudo[2103]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:50 volumio volumio[2003]: Starting albumart workers Feb 10 07:08:50 volumio volumio[2004]: Starting albumart workers Feb 10 07:08:50 volumio volumio[2006]: Starting albumart workers Feb 10 07:08:50 volumio volumio[1876]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 07:08:50 volumio volumio[1876]: info: Discovery: Found device Volumio Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:50 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:50 volumio volumio[1876]: info: MPD Permissions set Feb 10 07:08:50 volumio volumio[1876]: info: MPD Permissions set Feb 10 07:08:50 volumio volumio[1876]: info: Upmpdcli Daemon Started Feb 10 07:08:50 volumio volumio[1876]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 07:08:50 volumio volumio[1876]: info: Discovery: Found device Volumio Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:50 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:50 volumio volumio[1876]: info: Volumio called home Feb 10 07:08:50 volumio volumio[1876]: info: Spotify config file written Feb 10 07:08:50 volumio sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 07:08:50 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:50 volumio volumio[1876]: 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 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:50 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 10 07:08:50 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 10 07:08:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:50 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:51 volumio go-librespot[2116]: go-librespot daemon starting... Feb 10 07:08:51 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+07:00" level=debug msg="app state loaded" Feb 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:08:51 volumio volumio[1876]: info: No need to fix Spotify hosts Feb 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+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 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+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 10 07:08:51 volumio go-librespot[2119]: time="2026-02-10T07:08:51+07:00" level=info msg="zeroconf server listening on port 41223" Feb 10 07:08:52 volumio go-librespot[2119]: time="2026-02-10T07:08:52+07:00" level=debug msg="obtained new client token: AAAa1zbzPzAwGk5n96mpr7KI3okud19m71SmJqUO4Bx8DPzAZXH+IhKB2s7Tyb+0p0dtODzpJapDziYenC1FimO7O2UAwLvESoPMUJb7Yh1MySkLDtJ19eMF50n4ApObVZ+N/5wHnzbWY8nlMjVrnoWBf0/vQlmcLw/UzXjWvTMX2rHysjN5Xhh/1Oi90YUDkdDf0CYVFPa8lVSwJMhdR+zJ1X0/kP0kpu/2sR+qjomdao2LiGDwu7g=" Feb 10 07:08:52 volumio go-librespot[2119]: time="2026-02-10T07:08:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:52 volumio go-librespot[2119]: time="2026-02-10T07:08:52+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:52 volumio go-librespot[2119]: time="2026-02-10T07:08:52+07:00" level=debug msg="completed challenge" Feb 10 07:08:52 volumio go-librespot[2119]: time="2026-02-10T07:08:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:52 volumio volumio[1876]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 07:08:52 volumio volumio[1876]: SPOTIFY: BQBZqs9NX0u1IeGFfB-034sHTKq6-9WJYtsAES7hnHs_saZ6IW2GWymJaNo2jDZlMwtwRxVGhivOX5Dm2cUoB5NUP_YOMq3sleTOjcu4354slwhQV2W4QaF_S3GJEcEjWOWKiDM3H9Sb7ykb0Afxbn-LH1f0YSuTkhzGr87HfcPEZ23CE0Dl3CYynnsnFP2k2Y0wfliQLirGJyvMiCKz3Jq2whMA940SXLVlHxja8Miy4J2OQfI64yM2pKrvqwFoQPCMctlfuxLgjr0EGJ8554QZiI0wy68uMD5fKSQm9S3y8aS474kZvuTg Feb 10 07:08:52 volumio volumio[1876]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 07:08:52 volumio volumio[1876]: info: New Spotify access token = BQBZqs9NX0u1IeGFfB-034sHTKq6-9WJYtsAES7hnHs_saZ6IW2GWymJaNo2jDZlMwtwRxVGhivOX5Dm2cUoB5NUP_YOMq3sleTOjcu4354slwhQV2W4QaF_S3GJEcEjWOWKiDM3H9Sb7ykb0Afxbn-LH1f0YSuTkhzGr87HfcPEZ23CE0Dl3CYynnsnFP2k2Y0wfliQLirGJyvMiCKz3Jq2whMA940SXLVlHxja8Miy4J2OQfI64yM2pKrvqwFoQPCMctlfuxLgjr0EGJ8554QZiI0wy68uMD5fKSQm9S3y8aS474kZvuTg Feb 10 07:08:52 volumio volumio[1876]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 07:08:52 volumio volumio[1876]: info: Starting Shairport Sync Feb 10 07:08:52 volumio volumio[1876]: info: Starting Shairport Sync Feb 10 07:08:52 volumio volumio[1876]: info: Starting Shairport Sync Feb 10 07:08:52 volumio sudo[2151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:08:52 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:53 volumio sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:08:53 volumio sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:08:53 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:53 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:08:53 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 07:08:53 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 07:08:53 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:08:53 volumio systemd[1]: shairport-sync.service: Consumed 2.353s CPU time. Feb 10 07:08:53 volumio volumio[1876]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:53 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:53 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:08:53 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:53 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:53 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:53 volumio volumio[1876]: info: Shairport-Sync Started Feb 10 07:08:53 volumio volumio[1876]: Error adding Membership: Error: addMembership EINVAL Feb 10 07:08:53 volumio volumio[1876]: info: Shairport-Sync Started Feb 10 07:08:53 volumio volumio[1876]: info: Shairport-Sync Started Feb 10 07:08:53 volumio volumio[1876]: 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 10 07:08:53 volumio volumio[1876]: info: Spotify Successfully logged in Feb 10 07:08:53 volumio volumio[1876]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:08:53 volumio volumio[1876]: info: [1770682133612] CoreMusicLibrary::Adding element Spotify Feb 10 07:08:53 volumio volumio[1876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:08:53 volumio volumio[1876]: Cannot find translation for source YouTube Music Feb 10 07:08:53 volumio volumio[1876]: Cannot find translation for source Spotify Feb 10 07:08:53 volumio volumio[1876]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 10 07:08:53 volumio volumio[1876]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:08:54 volumio volumio[1876]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:08:54 volumio volumio[1876]: info: CoreCommandRouter::volumioGetState Feb 10 07:08:54 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:54 volumio volumio[1876]: info: CoreStateMachine::pushState Feb 10 07:08:54 volumio volumio[1876]: info: CorePlayQueue::getTrack 0 Feb 10 07:08:54 volumio volumio[1876]: info: CoreCommandRouter::volumioPushState Feb 10 07:08:55 volumio mpd[2099]: 2026-02-10T07:08:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 07:08:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 10 07:08:55 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 07:08:55 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:55 volumio sudo[2065]: pam_unix(sudo:session): session closed for user root Feb 10 07:08:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:55 volumio go-librespot[2193]: go-librespot daemon starting... Feb 10 07:08:55 volumio go-librespot[2196]: time="2026-02-10T07:08:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:55 volumio go-librespot[2196]: time="2026-02-10T07:08:55+07:00" level=debug msg="app state loaded" Feb 10 07:08:55 volumio go-librespot[2196]: time="2026-02-10T07:08:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:08:55 volumio volumio[1876]: error: MPD error: The expression evaluated to a falsy value: Feb 10 07:08:55 volumio volumio[1876]: assert.ok(self.idling) Feb 10 07:08:55 volumio volumio[1876]: error: The expression evaluated to a falsy value: Feb 10 07:08:55 volumio volumio[1876]: assert.ok(self.idling) Feb 10 07:08:55 volumio volumio[1876]: info: go-librespot daemon successfully initialized Feb 10 07:08:55 volumio volumio[1876]: info: MPD running with PID2099 Feb 10 07:08:55 volumio volumio[1876]: ,establishing connection Feb 10 07:08:55 volumio volumio[1876]: error: updateQueue error: null Feb 10 07:08:55 volumio volumio[1876]: info: Completed starting Core Plugins Feb 10 07:08:55 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:55 volumio volumio[1876]: info: ----- MyVolumio plugins startup ---- Feb 10 07:08:55 volumio volumio[1876]: info: ------------------------------------------- Feb 10 07:08:55 volumio volumio[1876]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 07:08:55 volumio volumio[1876]: error: updateQueue error: null Feb 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+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 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+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 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+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 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+07:00" level=info msg="zeroconf server listening on port 40093" Feb 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+07:00" level=debug msg="obtained new client token: AAD1lPhJQzIndpftnHKHqsKisp5z5JJdM9hKwflYcvhBMuqpPBWKqgC0ulnIEyxjb1pbDLe33gAWdeaKQ+xuBsURzSxAIMalprlff4GbTAKEfS7o6OByqn04ovobiG2hQbPjYvOAPoUl6pqnLa+8hvfm8e2LV2fAJ75O712ivwcUPaakzSVig7GA9IRMefLQShzHYF/lXMHN2yVVhUcCkjA5OQIwCzI2dWLOVPikruHw90pgBew2pOxeVQ==" Feb 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+07:00" level=debug msg="completed keyexchange" Feb 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+07:00" level=debug msg="completed challenge" Feb 10 07:08:56 volumio go-librespot[2196]: time="2026-02-10T07:08:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:08:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:08:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:08:58 volumio volumio[1876]: info: Initializing connection to go-librespot Websocket Feb 10 07:08:58 volumio volumio[1876]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:08:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 10 07:08:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:08:59 volumio go-librespot[2203]: go-librespot daemon starting... Feb 10 07:08:59 volumio go-librespot[2204]: time="2026-02-10T07:08:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:08:59 volumio go-librespot[2204]: time="2026-02-10T07:08:59+07:00" level=debug msg="app state loaded" Feb 10 07:08:59 volumio go-librespot[2204]: time="2026-02-10T07:08:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+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 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+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 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+07:00" level=info msg="zeroconf server listening on port 38591" Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+07:00" level=debug msg="obtained new client token: AADije7IJnbZnY+6O/pgfR8gdw05aYNcSB4mif0BwI9qjtQeGsrlIYCUHZxUt0V6nkqInifwuA+XXRNLbnDjsZoDSgR5YJUHkHjlTlCtRBrhXCwc0Xa6Ro/aSF53C1EwOlBpFYirpHXREBZnkP8VRn1BrH2uB3IJtixaMGmSbC6H2eOOH7UU771S+yXMYVO2DqXkMs346J10Jvei6KmIyc043EKx9CvtOyXs+RaAQjtk075j7xIg3X35vA==" Feb 10 07:09:00 volumio volumio[1876]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+07:00" level=debug msg="completed challenge" Feb 10 07:09:00 volumio go-librespot[2204]: time="2026-02-10T07:09:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:01 volumio volumio[1876]: info: Initializing connection to go-librespot Websocket Feb 10 07:09:01 volumio volumio[1876]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:09:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 10 07:09:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:04 volumio go-librespot[2213]: go-librespot daemon starting... Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="app state loaded" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=info msg="zeroconf server listening on port 43279" Feb 10 07:09:04 volumio volumio[1876]: info: Initializing connection to go-librespot Websocket Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="new websocket client" Feb 10 07:09:04 volumio volumio[1876]: info: Connection to go-librespot Websocket established Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="obtained new client token: AAAMu9eJYbcsauCXE1Bhu9FvGu3idZeTvADOc2vbojrN5guHNYvGBhLkTUqpveJ3OoD1jaB7yYqknv5LUDptL8odlpWAZhFUafH5N9dpaM0K3WWpm+95bRkCGKaeImPt6WVuelLHPN0aB6B9GXK/odRnxX7Hf6Z155gei3ne+q3tAchl4TnfE++RiXUejuMNprm7HqA883JbbKb/ij+d0yEwqzvCwwYfLAn7DjGvwVQ8PscTrSSM6t697w==" Feb 10 07:09:04 volumio go-librespot[2214]: time="2026-02-10T07:09:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:05 volumio go-librespot[2214]: time="2026-02-10T07:09:05+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:05 volumio go-librespot[2214]: time="2026-02-10T07:09:05+07:00" level=debug msg="completed challenge" Feb 10 07:09:05 volumio go-librespot[2214]: time="2026-02-10T07:09: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 10 07:09:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:05 volumio volumio[1876]: info: Connection to go-librespot Websocket closed Feb 10 07:09:07 volumio volumio[1876]: info: Getting Spotify volume Feb 10 07:09:07 volumio volumio[1876]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 07:09:07 volumio volumio[1876]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:09:07 volumio volumio[1876]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 10 07:09:07 volumio volumio[1876]: errno: -111, Feb 10 07:09:07 volumio volumio[1876]: code: 'ECONNREFUSED', Feb 10 07:09:07 volumio volumio[1876]: syscall: 'connect', Feb 10 07:09:07 volumio volumio[1876]: address: '127.0.0.1', Feb 10 07:09:07 volumio volumio[1876]: port: 9879, Feb 10 07:09:07 volumio volumio[1876]: response: undefined Feb 10 07:09:07 volumio volumio[1876]: } Feb 10 07:09:07 volumio volumio[1876]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 07:09:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 10 07:09:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:08 volumio go-librespot[2249]: go-librespot daemon starting... Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=debug msg="app state loaded" Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:09:08 volumio go-librespot[2250]: time="2026-02-10T07:09:08+07:00" level=info msg="zeroconf server listening on port 36883" Feb 10 07:09:09 volumio go-librespot[2250]: time="2026-02-10T07:09:09+07:00" level=debug msg="obtained new client token: AAB0ES4IJxMoD5hmvgLT2wHJkzmHXEiU4vP2kG6WwT8GHc/9WA3EhRTDEsnEXBEEF5nnw8luj/HgXNY01p9yTIaXQ+k7auCN03QBNRrqLY5D2KQMpGZ7I7etbVsTUDx+epi/0oqtxtQlWB9GoHcy/EA4hGeozuM8dqgqXQQEMZ8OlRqS/2RwD6rLL0VALFHzmfmzMWX24yNQNQOOrRbTpHIGdadZUFjyl2mQVi+fsrrAaYiphN/AIHI=" Feb 10 07:09:09 volumio go-librespot[2250]: time="2026-02-10T07:09:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:09 volumio go-librespot[2250]: time="2026-02-10T07:09:09+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:09 volumio go-librespot[2250]: time="2026-02-10T07:09:09+07:00" level=debug msg="completed challenge" Feb 10 07:09:09 volumio sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 07:08' Feb 10 07:09:09 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:09 volumio go-librespot[2250]: time="2026-02-10T07:09:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:09 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:09 volumio volumio-remote-updater[643]: [2026-02-10 07:09:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 10 07:09:09 volumio volumio-remote-updater[643]: [2026-02-10 07:09:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 10 07:09:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 10 07:09:09 volumio systemd[1]: volumio.service: Consumed 49.206s CPU time. Feb 10 07:09:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 07:09:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 07:09:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9882. Feb 10 07:09:10 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 07:09:10 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 10 07:09:10 volumio systemd[1]: volumio.service: Consumed 49.206s CPU time. Feb 10 07:09:10 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 10 07:09:10 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 07:09:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 10 07:09:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:12 volumio go-librespot[2286]: go-librespot daemon starting... Feb 10 07:09:12 volumio go-librespot[2287]: time="2026-02-10T07:09:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:12 volumio go-librespot[2287]: time="2026-02-10T07:09:12+07:00" level=debug msg="app state loaded" Feb 10 07:09:12 volumio go-librespot[2287]: time="2026-02-10T07:09:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=info msg="zeroconf server listening on port 40977" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="obtained new client token: AAC4UZ9gJ+8e3e4c6XtGNxjbtdDEEn/h/HsqYrI6CdQuk+aegGx33R3zcri3ZttBF3GHljzWDl+ytTqjBlyivKWyp6xnvu6JlsXKbstakyF6Bg+Hmq3+A0fRdve3cRIk4/LDfzl1G9yVayKra5HQV50rl7RRGEFbs3SXoG/gyD0hN7GPmo+GA9OMFGzRbghaS/hoo4HMW+9j02gR5yiQysJhssXn/BsbJVJ17XBnj2rjobNANhnerco++w==" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+07:00" level=debug msg="completed challenge" Feb 10 07:09:13 volumio go-librespot[2287]: time="2026-02-10T07:09:13+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 10 07:09:13 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:13 volumio volumio[2271]: info: ----- Volumio3 ---- Feb 10 07:09:13 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:13 volumio volumio[2271]: info: ----- System startup ---- Feb 10 07:09:13 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:14 volumio volumio-remote-updater[643]: [2026-02-10 07:09:14] [connect] Successful connection Feb 10 07:09:15 volumio volumio[2271]: info: MYVOLUMIO Environment detected Feb 10 07:09:15 volumio volumio[2271]: info: Plugin folders cleanup Feb 10 07:09:15 volumio volumio[2271]: info: Scanning into folder /volumio/app/plugins/ Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category audio_interface Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category miscellanea Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category music_service Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category plugins.json Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category system_controller Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category user_interface Feb 10 07:09:15 volumio volumio[2271]: info: Scanning into folder /data/plugins/ Feb 10 07:09:15 volumio volumio[2271]: info: Scanning category music_service Feb 10 07:09:15 volumio volumio[2271]: info: Plugin folders cleanup completed Feb 10 07:09:15 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:15 volumio volumio[2271]: info: ----- Core plugins startup ---- Feb 10 07:09:15 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:15 volumio volumio[2271]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 07:09:15 volumio volumio[2271]: info: Adding plugin upnp to MyMusic Plugins Feb 10 07:09:15 volumio volumio[2271]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 07:09:15 volumio volumio[2271]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 07:09:15 volumio volumio[2271]: info: Loading plugins from folder /data/plugins/ Feb 10 07:09:15 volumio volumio[2271]: info: Loading plugin "system"... Feb 10 07:09:15 volumio volumio[2271]: info: Loading plugin "appearance"... Feb 10 07:09:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 10 07:09:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:16 volumio go-librespot[2307]: go-librespot daemon starting... Feb 10 07:09:16 volumio go-librespot[2308]: time="2026-02-10T07:09:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:16 volumio go-librespot[2308]: time="2026-02-10T07:09:16+07:00" level=debug msg="app state loaded" Feb 10 07:09:16 volumio go-librespot[2308]: time="2026-02-10T07:09:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "network"... Feb 10 07:09:17 volumio volumio[2271]: info: Refreshing Cached IP Addresses Feb 10 07:09:17 volumio sudo[2316]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 07:09:17 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:17 volumio sudo[2318]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 07:09:17 volumio sudo[2318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:17 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "services"... Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "alsa_controller"... Feb 10 07:09:17 volumio sudo[2318]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:17 volumio sudo[2326]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 07:09:17 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:17 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "wizard"... Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "networkfs"... Feb 10 07:09:17 volumio go-librespot[2308]: time="2026-02-10T07:09:17+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:09:17 volumio go-librespot[2308]: time="2026-02-10T07:09:17+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 10 07:09:17 volumio go-librespot[2308]: time="2026-02-10T07:09:17+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 10 07:09:17 volumio volumio[2271]: info: Starting Udev Watcher for removable devices Feb 10 07:09:17 volumio go-librespot[2308]: time="2026-02-10T07:09:17+07:00" level=info msg="zeroconf server listening on port 41449" Feb 10 07:09:17 volumio volumio[2271]: info: Ignoring mount for partition: boot Feb 10 07:09:17 volumio volumio[2271]: info: Ignoring mount for partition: volumio Feb 10 07:09:17 volumio volumio[2271]: info: Ignoring mount for partition: volumio_data Feb 10 07:09:17 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "volumio_command_line_client"... Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "upnp"... Feb 10 07:09:17 volumio volumio[2271]: info: [1770682157636] Starting Upmpd Daemon Feb 10 07:09:17 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "my_music"... Feb 10 07:09:17 volumio volumio[2271]: info: Loading plugin "mpd"... Feb 10 07:09:17 volumio go-librespot[2308]: time="2026-02-10T07:09:17+07:00" level=debug msg="obtained new client token: AAALKUgdAQTBJuVKDI4Ru35xKI3tBHtZIPa/3TKIl3dADuRfRp7imD6UB7pDePrTnaFzZEMMrblyHCttn31h9gSnXd7/JyFGta7m3qQOG4vEmek6Fk7d3byQeDLe4nf1OipNNuVafrYhky2GOJXaX3fd2MXT+jUgzBt5ert6mC4N/jP6fdD8Cgpw4b9Jizo/vmMvl97Bw9r5QtiZR9JcVmCaoVBp6WFkRcYuzBiXBwEEriMP3jkf1RixDg==" Feb 10 07:09:17 volumio go-librespot[2308]: time="2026-02-10T07:09:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:18 volumio go-librespot[2308]: time="2026-02-10T07:09:18+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:18 volumio go-librespot[2308]: time="2026-02-10T07:09:18+07:00" level=debug msg="completed challenge" Feb 10 07:09:18 volumio go-librespot[2308]: time="2026-02-10T07:09:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:18 volumio volumio[2271]: info: Loading plugin "upnp_browser"... Feb 10 07:09:20 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:21 volumio volumio[2271]: info: Starting UPNP Browser Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "alarm-clock"... Feb 10 07:09:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 10 07:09:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:21 volumio go-librespot[2349]: go-librespot daemon starting... Feb 10 07:09:21 volumio go-librespot[2350]: time="2026-02-10T07:09:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:21 volumio go-librespot[2350]: time="2026-02-10T07:09:21+07:00" level=debug msg="app state loaded" Feb 10 07:09:21 volumio go-librespot[2350]: time="2026-02-10T07:09:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "airplay_emulation"... Feb 10 07:09:21 volumio volumio[2271]: info: Starting Shairport Sync Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "last_100"... Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "webradio"... Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "i2s_dacs"... Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "volumiodiscovery"... Feb 10 07:09:21 volumio volumio[2271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 07:09:21 volumio volumio[2271]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:09:21 volumio volumio[2271]: *** WARNING *** For more information see Feb 10 07:09:21 volumio volumio[2271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 07:09:21 volumio volumio[2271]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:09:21 volumio volumio[2271]: *** WARNING *** For more information see Feb 10 07:09:21 volumio node[2271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 07:09:21 volumio node[2271]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:09:21 volumio node[2271]: *** WARNING *** For more information see Feb 10 07:09:21 volumio node[2271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 07:09:21 volumio node[2271]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 07:09:21 volumio node[2271]: *** WARNING *** For more information see Feb 10 07:09:21 volumio volumio[2271]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 07:09:21 volumio volumio[2271]: info: Discovery: Started advertising with name: Volumio Feb 10 07:09:21 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 07:09:21 volumio volumio[2271]: info: Loading plugin "spop"... Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+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 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+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 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=info msg="zeroconf server listening on port 36317" Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=debug msg="obtained new client token: AAAkOEdgeqF0XfOkB0zuCo+2IsPUnjHf5z/mGQSu9zAUOX683Dj6dWjtd/g6bDR+iF4Rdp7o9bPaKq3AGdQAeqk3uy7ePq5d7QRTE+w+WQ0N/gYXWImC4tplM3Pzl4Aml0RqahVmU7D6U9SDgniqktgsMD6MJVvMtTe1WAt14aCvTF0Y9SNVIlerUGecaLYTsDRyV+S/z5IvptmhzHb17D6YlDO53mvtSoijCjHT32lpCKTQYdlRC3w=" Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=debug msg="completed challenge" Feb 10 07:09:22 volumio go-librespot[2350]: time="2026-02-10T07:09:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:23 volumio volumio[2271]: info: Loading plugin "ytcr"... Feb 10 07:09:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 10 07:09:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:25 volumio go-librespot[2359]: go-librespot daemon starting... Feb 10 07:09:25 volumio go-librespot[2360]: time="2026-02-10T07:09:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:25 volumio go-librespot[2360]: time="2026-02-10T07:09:25+07:00" level=debug msg="app state loaded" Feb 10 07:09:25 volumio go-librespot[2360]: time="2026-02-10T07:09:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+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 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+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 10 07:09:26 volumio volumio[2271]: info: Loading plugin "ytmusic"... Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=info msg="zeroconf server listening on port 36013" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=debug msg="obtained new client token: AAA10DthjLnKkY5r1HSu/HAOv1CalwaFZvcxDAQw2FYoKM9dIGDvmxMmCsSAG2eHjqybDXG0+J4YWrqE4DW4h6F5pkyU0KxTDYcecSQGFfPVqf+Aw69ePEp1R6lfi7udaCyljG+fpYHs4RRXAgCzvAgEXJgYjXqKtmAVdXxa24Zf5r1mPNIzP1/BOO6c+OIGU88GxSo/tgd6OOWmnGPkVg6Ec5Pcv81XJuMrPccjcDtGlwaYkQjNfmqBYA==" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=debug msg="completed challenge" Feb 10 07:09:26 volumio go-librespot[2360]: time="2026-02-10T07:09:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:27 volumio volumio-remote-updater[643]: [2026-02-10 07:09:27] [connect] Successful connection Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "outputs"... Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "albumart"... Feb 10 07:09:27 volumio volumio[2271]: info: Plugin example_plugin is not enabled Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "inputs"... Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "updater_comm"... Feb 10 07:09:27 volumio volumio[2271]: info: Plugin mpdemulation is not enabled Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "rest_api"... Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "websocket"... Feb 10 07:09:27 volumio volumio[2271]: info: Starting Socket.io Server version 1.7.4 Feb 10 07:09:27 volumio volumio[2271]: info: Loading plugin "RoonBridge"... Feb 10 07:09:28 volumio volumio[2271]: info: Applying required configuration parameters for plugin RoonBridge Feb 10 07:09:28 volumio volumio[2271]: info: Loading i18n strings for locale en Feb 10 07:09:28 volumio volumio[2271]: Updating browse sources language Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:09:28 volumio volumio[2383]: Forking 3 albumart workers Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::initPlayerControls Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:09:28 volumio volumio[2271]: Express server listening on port 3000 Feb 10 07:09:28 volumio volumio[2271]: [Metrics] WebUI: 15s 982.10ms Feb 10 07:09:28 volumio volumio[2271]: info: CoreStateMachine::resetVolumioState Feb 10 07:09:28 volumio volumio[2271]: info: CoreStateMachine::getcurrentVolume Feb 10 07:09:28 volumio volumio[2271]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:09:28 volumio sudo[2426]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 07:09:28 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:28 volumio sudo[2428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 07:09:29 volumio sudo[2428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:29 volumio volumio[2271]: info: Volumio Network Manager: Network status updated: 1 Feb 10 07:09:29 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:29 volumio sudo[2428]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:29 volumio volumio[2271]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::pushState Feb 10 07:09:29 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::volumioPushState Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::updateTrackBlock Feb 10 07:09:29 volumio volumio[2271]: info: CorePlayQueue::getTrackBlock Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:09:29 volumio volumio-remote-updater[643]: [2026-02-10 07:09:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770682167 101 Feb 10 07:09:29 volumio volumio[2271]: 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 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:29 volumio volumio[2271]: info: Reloading queue from file Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::setRepeat null single undefined Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::pushState Feb 10 07:09:29 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::volumioPushState Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::setRandom null Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::pushState Feb 10 07:09:29 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::volumioPushState Feb 10 07:09:29 volumio volumio[2271]: info: Setting Device type: Raspberry PI Feb 10 07:09:29 volumio volumio[2271]: info: Completed loading Core Plugins Feb 10 07:09:29 volumio volumio[2271]: info: Preparing to generate the ALSA configuration file Feb 10 07:09:29 volumio volumio[2271]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:09:29 volumio volumio[2271]: info: CoreStateMachine::pushState Feb 10 07:09:29 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::volumioPushState Feb 10 07:09:29 volumio volumio[2271]: info: Asound.conf file unchanged, so no further update is needed Feb 10 07:09:29 volumio volumio[2271]: info: Output device has changed, restarting MPD Feb 10 07:09:29 volumio volumio[2271]: info: Output device has changed, restarting Shairport Sync Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:29 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:29 volumio sudo[2446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 07:09:29 volumio sudo[2444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 07:09:29 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:29 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:29 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:29 volumio volumio[2271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:09:29 volumio volumio[2271]: info: ___________ START PLUGINS ___________ Feb 10 07:09:29 volumio sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 07:09:29 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:29 volumio volumio[2271]: info: ControllerMpd::onStart: Initializing MPD Feb 10 07:09:29 volumio volumio[2271]: info: Creating MPD Configuration file Feb 10 07:09:29 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:09:30 volumio volumio[2271]: info: [1770682170050] CoreMusicLibrary::Adding element Media Servers Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:09:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 10 07:09:30 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 07:09:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:30 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:30 volumio sudo[2459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 07:09:30 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:30 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:30 volumio volumio[2271]: info: UPNP Browser: Client initialized successfully Feb 10 07:09:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:30 volumio go-librespot[2461]: go-librespot daemon starting... Feb 10 07:09:30 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+07:00" level=debug msg="app state loaded" Feb 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 07:09:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 07:09:30 volumio systemd[1]: mpd.service: Consumed 7.176s CPU time. Feb 10 07:09:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 07:09:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 07:09:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 07:09:30 volumio volumio[2271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:30 volumio volumio[2271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 07:09:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:09:30 volumio volumio[2271]: info: [1770682170457] CoreMusicLibrary::Adding element Last_100 Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:09:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:09:30 volumio volumio[2271]: info: [1770682170476] CoreMusicLibrary::Adding element Webradio Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:09:30 volumio volumio[2271]: info: Initializing BBC Radios Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:30 volumio volumio[2271]: info: Creating Spotify config file Feb 10 07:09:30 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+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 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+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 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+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 10 07:09:30 volumio sudo[2479]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 07:09:30 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 07:09:30 volumio go-librespot[2464]: time="2026-02-10T07:09:30+07:00" level=info msg="zeroconf server listening on port 35713" Feb 10 07:09:31 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:31 volumio go-librespot[2464]: time="2026-02-10T07:09:31+07:00" level=debug msg="obtained new client token: AAAcYVNUKcwraWkbgFBiF1PssRlFckixMJ3i6THw01QtHWnIg681jZ2AOiEORPDR3ccY2y7sZye/6tlwLUCACeCEXtEzZgO+wbm2TswJRDqe4pUkr+0HX9aiaXQ1z8p3K7Jr7gxXDjCmXmS7RTKvYtqqTy4b8hSRIoS1f2A2iwFVkTef4vXEu/zD3o2pqbln2w11mioopemOhdlrK2TCZfIFXqzRrUj7Oj9E+Pea4cFFLmnNBMxisBM=" Feb 10 07:09:31 volumio go-librespot[2464]: time="2026-02-10T07:09:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:31 volumio go-librespot[2464]: time="2026-02-10T07:09:31+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:31 volumio go-librespot[2464]: time="2026-02-10T07:09:31+07:00" level=debug msg="completed challenge" Feb 10 07:09:31 volumio go-librespot[2464]: time="2026-02-10T07:09:31+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 10 07:09:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:32 volumio volumio[2393]: Starting albumart workers Feb 10 07:09:32 volumio volumio[2395]: Starting albumart workers Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:09:32 volumio volumio[2271]: info: [1770682172092] CoreMusicLibrary::Adding element YouTube Music Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:09:32 volumio volumio[2271]: Cannot find translation for source YouTube Music Feb 10 07:09:32 volumio volumio[2271]: info: Volumio Calling Home Feb 10 07:09:32 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 10 07:09:32 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:32 volumio volumio[2394]: Starting albumart workers Feb 10 07:09:32 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:32 volumio volumio[2271]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 07:09:32 volumio volumio[2271]: info: Discovery: Found device Volumio Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::volumioGetState Feb 10 07:09:32 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:32 volumio volumio[2271]: info: MPD Permissions set Feb 10 07:09:32 volumio volumio[2271]: info: MPD Permissions set Feb 10 07:09:32 volumio volumio[2271]: info: Upmpdcli Daemon Started Feb 10 07:09:32 volumio volumio[2271]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 10 07:09:32 volumio volumio[2271]: info: Discovery: Found device Volumio Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::volumioGetState Feb 10 07:09:32 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:32 volumio volumio[2271]: info: Spotify config file written Feb 10 07:09:32 volumio volumio[2271]: 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 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio sudo[2504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 07:09:32 volumio sudo[2504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:32 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:33 volumio go-librespot[2513]: go-librespot daemon starting... Feb 10 07:09:33 volumio sudo[2504]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 07:09:33 volumio volumio[2271]: info: No need to fix Spotify hosts Feb 10 07:09:33 volumio go-librespot[2517]: time="2026-02-10T07:09:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:33 volumio go-librespot[2517]: time="2026-02-10T07:09:33+07:00" level=debug msg="app state loaded" Feb 10 07:09:33 volumio go-librespot[2517]: time="2026-02-10T07:09:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:33 volumio go-librespot[2517]: time="2026-02-10T07:09:33+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:09:33 volumio go-librespot[2517]: time="2026-02-10T07:09:33+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 07:09:33 volumio go-librespot[2517]: time="2026-02-10T07:09:33+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 07:09:34 volumio go-librespot[2517]: time="2026-02-10T07:09:34+07:00" level=info msg="zeroconf server listening on port 40945" Feb 10 07:09:34 volumio go-librespot[2517]: time="2026-02-10T07:09:34+07:00" level=debug msg="obtained new client token: AAAu57gvMnx9cAMQFLlHCodczwBC9iWrFSqHs+ZA4p3jeUxyDMV5WBvD6XJfvDTlrwWvyLzIGW7Ze27sgN5pdUXexjCEcTTOoD1c+IqIIuz9Rd33bbTb20Xnxsdz8dmzKFR4Du5h61VK/uhyHzQhDTanhI2vACjK3E1730ufgIn6K6Fmr1bKwQtERhiOrd7gOMOMj6FHMZ3nR+/xwiMrUn0kTij7VYSuWNUiidtmV6ueeT7DOMvAhyU=" Feb 10 07:09:34 volumio go-librespot[2517]: time="2026-02-10T07:09:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:34 volumio go-librespot[2517]: time="2026-02-10T07:09:34+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:34 volumio go-librespot[2517]: time="2026-02-10T07:09:34+07:00" level=debug msg="completed challenge" Feb 10 07:09:34 volumio volumio[2271]: info: Volumio called home Feb 10 07:09:34 volumio go-librespot[2517]: time="2026-02-10T07:09: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 10 07:09:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:34 volumio volumio[2271]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 07:09:34 volumio volumio[2271]: SPOTIFY: BQCURwCOeFECt8ko1-0u34uNEFvz-UQ8Pd7qE75WS8rBcL-UyhVmntMnMWohDDfLREsBF73VtouT5QFukt6SO_Jx9-gaGS008_C3N7dl51wHrXDcRrnr9JGYX00nNHkiSt-n_zvMwXHGR_yM7Uqdusm7jKd6V1fljbs13oW-ZHa22U1dmmMXXwDMPB5iF-16ZwNdDsFp8oIX0qx5WJYxpeCEBgDMDxbboiPJ4CJ6hQrufq1c0ItAwTML21NiqoZ98oLLXvpzNPxTyT4dbXkSENvxj_ZZqSFymn8ez24P1DsI1AAUL5EVqYh7 Feb 10 07:09:34 volumio volumio[2271]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 07:09:34 volumio volumio[2271]: info: New Spotify access token = BQCURwCOeFECt8ko1-0u34uNEFvz-UQ8Pd7qE75WS8rBcL-UyhVmntMnMWohDDfLREsBF73VtouT5QFukt6SO_Jx9-gaGS008_C3N7dl51wHrXDcRrnr9JGYX00nNHkiSt-n_zvMwXHGR_yM7Uqdusm7jKd6V1fljbs13oW-ZHa22U1dmmMXXwDMPB5iF-16ZwNdDsFp8oIX0qx5WJYxpeCEBgDMDxbboiPJ4CJ6hQrufq1c0ItAwTML21NiqoZ98oLLXvpzNPxTyT4dbXkSENvxj_ZZqSFymn8ez24P1DsI1AAUL5EVqYh7 Feb 10 07:09:34 volumio volumio[2271]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 07:09:34 volumio volumio[2271]: info: Starting Shairport Sync Feb 10 07:09:34 volumio volumio[2271]: info: Starting Shairport Sync Feb 10 07:09:34 volumio volumio[2271]: info: Starting Shairport Sync Feb 10 07:09:34 volumio sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:09:34 volumio sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:34 volumio sudo[2539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:09:34 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:34 volumio sudo[2541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 07:09:34 volumio sudo[2541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 07:09:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 07:09:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:09:35 volumio systemd[1]: shairport-sync.service: Consumed 2.191s CPU time. Feb 10 07:09:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 07:09:35 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:35 volumio sudo[2537]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:35 volumio sudo[2541]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:35 volumio volumio[2271]: info: Shairport-Sync Started Feb 10 07:09:35 volumio volumio[2271]: Error adding Membership: Error: addMembership EINVAL Feb 10 07:09:35 volumio volumio[2271]: info: Shairport-Sync Started Feb 10 07:09:35 volumio volumio[2271]: info: Shairport-Sync Started Feb 10 07:09:35 volumio volumio[2271]: info: CoreCommandRouter::volumioGetState Feb 10 07:09:35 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:35 volumio volumio[2271]: 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 10 07:09:35 volumio volumio[2271]: info: Spotify Successfully logged in Feb 10 07:09:35 volumio volumio[2271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 07:09:35 volumio volumio[2271]: info: [1770682175485] CoreMusicLibrary::Adding element Spotify Feb 10 07:09:35 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 07:09:35 volumio volumio[2271]: Cannot find translation for source YouTube Music Feb 10 07:09:35 volumio volumio[2271]: Cannot find translation for source Spotify Feb 10 07:09:36 volumio volumio[2271]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 10 07:09:36 volumio volumio[2271]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 07:09:36 volumio volumio[2271]: info: VolumeController:: Volume=100 Mute =false Feb 10 07:09:36 volumio volumio[2271]: info: CoreCommandRouter::volumioGetState Feb 10 07:09:36 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:36 volumio volumio[2271]: info: CoreStateMachine::pushState Feb 10 07:09:36 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:36 volumio volumio[2271]: info: CoreCommandRouter::volumioPushState Feb 10 07:09:37 volumio volumio[2271]: info: go-librespot daemon successfully initialized Feb 10 07:09:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 10 07:09:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:37 volumio go-librespot[2584]: go-librespot daemon starting... Feb 10 07:09:37 volumio go-librespot[2585]: time="2026-02-10T07:09:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:37 volumio go-librespot[2585]: time="2026-02-10T07:09:37+07:00" level=debug msg="app state loaded" Feb 10 07:09:37 volumio go-librespot[2585]: time="2026-02-10T07:09:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:38 volumio mpd[2496]: 2026-02-10T07:09:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 07:09:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 07:09:38 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:38 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+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 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+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 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+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 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+07:00" level=info msg="zeroconf server listening on port 41791" Feb 10 07:09:38 volumio volumio[2271]: error: MPD error: The expression evaluated to a falsy value: Feb 10 07:09:38 volumio volumio[2271]: assert.ok(self.idling) Feb 10 07:09:38 volumio volumio[2271]: error: The expression evaluated to a falsy value: Feb 10 07:09:38 volumio volumio[2271]: assert.ok(self.idling) Feb 10 07:09:38 volumio volumio[2271]: error: updateQueue error: null Feb 10 07:09:38 volumio volumio[2271]: info: MPD running with PID2496 Feb 10 07:09:38 volumio volumio[2271]: ,establishing connection Feb 10 07:09:38 volumio volumio[2271]: info: Completed starting Core Plugins Feb 10 07:09:38 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:38 volumio volumio[2271]: info: ----- MyVolumio plugins startup ---- Feb 10 07:09:38 volumio volumio[2271]: info: ------------------------------------------- Feb 10 07:09:38 volumio volumio[2271]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 07:09:38 volumio volumio[2271]: error: updateQueue error: null Feb 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+07:00" level=debug msg="obtained new client token: AABVf89ITwzNMijxBJZd5runRmCJSgt/SqrxweJwR46b6TmTvITSg76obRoUInYBdK7FfcbjU31520AiiZAcbfosOnINriCfWsv6eU0FgRo3/fWNCzQRT6Q1TjmOU9jxoIRDZuuF0SP/Sr5ahtKPV65mWaXV71MDPqVxUDZpgO2/VDECCmginpmMD+REMk990fomICWR3XtcVklgIMivngrgJr173G++vzznWJiO5/G2JmC7WNebApz/ug==" Feb 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+07:00" level=debug msg="completed challenge" Feb 10 07:09:38 volumio go-librespot[2585]: time="2026-02-10T07:09:38+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:40 volumio volumio[2271]: info: Initializing connection to go-librespot Websocket Feb 10 07:09:40 volumio volumio[2271]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:09:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 10 07:09:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:41 volumio go-librespot[2596]: go-librespot daemon starting... Feb 10 07:09:41 volumio go-librespot[2597]: time="2026-02-10T07:09:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:41 volumio go-librespot[2597]: time="2026-02-10T07:09:41+07:00" level=debug msg="app state loaded" Feb 10 07:09:41 volumio go-librespot[2597]: time="2026-02-10T07:09:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09: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-gew4.spotify.com:80]" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+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 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+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 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+07:00" level=info msg="zeroconf server listening on port 33617" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+07:00" level=debug msg="obtained new client token: AABrDzpmu0C3R8gKyy3OxqN860NzquPqvFrgezvJumMWD0SCsq3hfxDv8LBHfIpC6GgZ6eNOWYImLLp2kMCnd79aAsTgkQMM7ZLzdFfmAscOWrTtWWFlqobdH+jBDyxZGaZHHQJkGByrQgZ3NvcvCFykSBHO5EOATk3ZYtJ81OljBet68BOJ5gNIZpo5WsLx8ofAmsi5RlHCHpq+dyVry0uSZll5sl0EHONXSEu4sZU1PvfU4RnYr5fmIg==" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+07:00" level=debug msg="completed challenge" Feb 10 07:09:42 volumio go-librespot[2597]: time="2026-02-10T07:09:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:43 volumio volumio[2271]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 07:09:43 volumio volumio[2271]: info: Initializing connection to go-librespot Websocket Feb 10 07:09:43 volumio volumio[2271]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 07:09:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 10 07:09:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:46 volumio go-librespot[2605]: go-librespot daemon starting... Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=debug msg="app state loaded" Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:46 volumio volumio[2271]: info: Initializing connection to go-librespot Websocket Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=debug msg="new websocket client" Feb 10 07:09:46 volumio volumio[2271]: info: Connection to go-librespot Websocket established Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09: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 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09: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 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09: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 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=info msg="zeroconf server listening on port 40661" Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=debug msg="obtained new client token: AAA6zJR9ZDfzzE/TFdpGGjC91TwFHk7Sp6uFCN5AP05qG7rypKFa4oZwk65In3BCEGea2ljkkXoIGQUwp5WjShiR42GX4AdS8il22//EBTmQHh2NEpyfgFun6jp20YEYLwGAjsfYspJDJHN2GXtnEOMLbd0DiLPNAC1MpOALVnNoc1/uJDFOrNs/xkANGFOhcgYGL8QKio7yyoYx0OmQeCAkg0gFCUU6xZ04JvzGxLdVhGJXOABo2Uj1kw==" Feb 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+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 10 07:09:46 volumio go-librespot[2606]: time="2026-02-10T07:09:46+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 10 07:09:47 volumio go-librespot[2606]: time="2026-02-10T07:09:47+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:47 volumio go-librespot[2606]: time="2026-02-10T07:09:47+07:00" level=debug msg="completed challenge" Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin bluetooth to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin multiroom to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin metavolumio to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin cd_controller to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 10 07:09:47 volumio volumio[2271]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 10 07:09:47 volumio go-librespot[2606]: time="2026-02-10T07:09: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 10 07:09:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:49 volumio volumio[2271]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 10 07:09:49 volumio volumio[2271]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 10 07:09:49 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:49 volumio volumio[2271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 07:09:49 volumio volumio[2271]: info: Starting MyVolumio Remote Streaming Endpoints Feb 10 07:09:49 volumio volumio[2271]: info: MyVolumio login type: Token Feb 10 07:09:49 volumio volumio[2271]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 10 07:09:49 volumio volumio[2271]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 10 07:09:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 10 07:09:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 07:09:50 volumio go-librespot[2627]: go-librespot daemon starting... Feb 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09:50+07:00" level=info msg="running go-librespot 0.4.0" Feb 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09:50+07:00" level=debug msg="app state loaded" Feb 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09:50+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 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09:50+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 10 07:09:50 volumio volumio[2271]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 10 07:09:50 volumio volumio[2271]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 10 07:09:50 volumio volumio[2271]: info: Streaming services startup Feb 10 07:09:50 volumio volumio[2271]: info: Starting Streaming Daemon Feb 10 07:09:50 volumio go-librespot[2628]: time="2026-02-10T07:09:50+07:00" level=info msg="zeroconf server listening on port 36279" Feb 10 07:09:51 volumio volumio[2271]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 10 07:09:51 volumio sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 07:09:51 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 07:09:51 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Feb 10 07:09:51 volumio volumio[2271]: info: Getting Spotify volume Feb 10 07:09:51 volumio go-librespot[2628]: time="2026-02-10T07:09:51+07:00" level=debug msg="obtained new client token: AABbfoGEMlQaWFsGShrz7Ym06obKSSjy2HRBZIV44/jddlHbHN/f1frcm/vaieQGmTjF3EKbPf0uxt8fdXLj3iiStGuh0GBn7GWr91Y2FkV9ZNM8UngKDvpnEft+5/CXm2tKcgKRzON5Mh6j/FLpTLafmFPq34Huup78omOE2I4g1aZ/lhSjC+W4uVGv987XDSiCbLZkJ8m2rRoeXBAb6mmDMnKgtVMrat33+Z586yQmz3gDs8B1cUc=" Feb 10 07:09:51 volumio volumio[2271]: info: Connection to go-librespot Websocket closed Feb 10 07:09:51 volumio volumio[2271]: error: Cannot start Volumio Streaming Daemon Feb 10 07:09:51 volumio volumio[2271]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 07:09:51 volumio volumio[2271]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 07:09:51 volumio volumio[2271]: 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 10 07:09:51 volumio go-librespot[2628]: time="2026-02-10T07:09:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 10 07:09:51 volumio go-librespot[2628]: time="2026-02-10T07:09:51+07:00" level=debug msg="completed keyexchange" Feb 10 07:09:51 volumio go-librespot[2628]: time="2026-02-10T07:09:51+07:00" level=debug msg="completed challenge" Feb 10 07:09:51 volumio volumio[2271]: info: CoreCommandRouter::volumioGetState Feb 10 07:09:51 volumio volumio[2271]: info: CorePlayQueue::getTrack 0 Feb 10 07:09:51 volumio go-librespot[2628]: time="2026-02-10T07:09:51+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 07:09:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 07:09:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 07:09:51 volumio volumio[2271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 07:09:51 volumio volumio[2271]: Error: socket hang up Feb 10 07:09:51 volumio volumio[2271]: at connResetException (node:internal/errors:720:14) Feb 10 07:09:51 volumio volumio[2271]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 10 07:09:51 volumio volumio[2271]: at Socket.emit (node:events:526:35) Feb 10 07:09:51 volumio volumio[2271]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 10 07:09:51 volumio volumio[2271]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 10 07:09:51 volumio volumio[2271]: code: 'ECONNRESET', Feb 10 07:09:51 volumio volumio[2271]: response: undefined Feb 10 07:09:51 volumio volumio[2271]: } Feb 10 07:09:51 volumio volumio[2271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 07:09:52 volumio sudo[2658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 07:08' Feb 10 07:09:52 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"