Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Proxy server started on port 33615 Feb 08 05:23:00 volumio volumio[29624]: info: Preparing to generate the ALSA configuration file Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:23:00 volumio volumio[29624]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 05:23:00 volumio volumio[29624]: info: Reading ALSA contributions from plugins. Feb 08 05:23:00 volumio volumio[29624]: info: MPD Permissions set Feb 08 05:23:00 volumio volumio[29624]: info: MPD Permissions set Feb 08 05:23:00 volumio volumio[29624]: info: Spotify config file written Feb 08 05:23:00 volumio sudo[29885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 05:23:00 volumio sudo[29885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:00 volumio volumio[29624]: 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 08 05:23:00 volumio volumio[29624]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:23:00 volumio volumio[29624]: info: CoreStateMachine::pushState Feb 08 05:23:00 volumio volumio[29624]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::volumioPushState Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Server discovery started Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Player finder started Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 08 05:23:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:00 volumio go-librespot[29888]: go-librespot daemon starting... Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio sudo[29885]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:00 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="app state loaded" Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:00 volumio volumio[29624]: info: No need to fix Spotify hosts Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:00 volumio volumio[29624]: 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 08 05:23:00 volumio volumio[29624]: info: Volumio called home Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=info msg="zeroconf server listening on port 42629" Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 08 05:23:00 volumio volumio[29624]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 05:23:00 volumio volumio[29624]: SPOTIFY: BQDX_kvFMot-ABXEfocuVitgAyRA42OnhjRLTRHjyBdPgcmIhQIoNsJPhg7ZtAtmQvXFqP_sTxhVfhCMZVmupDGuV2CoFRJbDBF6FNr6IZq4wPlxqx2NO89Wc3ahSXgCV4AelnVIljGJ5NA-NseQeRxP2Zf3pFuO7Om_5m8jDXVVX88E9Au3FkysaruAxCUdj-SDgQPQQPjYQfOJGtenUTYUDcrzlr8t6xAf-1E Feb 08 05:23:00 volumio volumio[29624]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 05:23:00 volumio volumio[29624]: info: New Spotify access token = BQDX_kvFMot-ABXEfocuVitgAyRA42OnhjRLTRHjyBdPgcmIhQIoNsJPhg7ZtAtmQvXFqP_sTxhVfhCMZVmupDGuV2CoFRJbDBF6FNr6IZq4wPlxqx2NO89Wc3ahSXgCV4AelnVIljGJ5NA-NseQeRxP2Zf3pFuO7Om_5m8jDXVVX88E9Au3FkysaruAxCUdj-SDgQPQQPjYQfOJGtenUTYUDcrzlr8t6xAf-1E Feb 08 05:23:00 volumio volumio[29624]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 05:23:00 volumio sudo[29909]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 08 05:23:00 volumio sudo[29909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:00 volumio sudo[29909]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="obtained new client token: AACbmBRF6PcODmgM3jxDKVUeGiYDlc/wq0e1ueb8K5DD6MK623CtzLbgpzc6mtY/vbbK/UYasMB22+lqu07Zwhh68pPSAxsAifQwNlTcaN0tdWaZvhuwMLY5QjU4dV0NGfSbAVZjAN01Rvy3EHqZWKP15S9tLZr3mVaz8lOh4W0u+WltM8eGdEfHK+7IIJTqfiXNdn8civPrlbFqadi6qknxDxEoHq5FKxe1MaUXe6s6Jqx/88QfyfXquA==" Feb 08 05:23:00 volumio volumio[29624]: info: Starting Shairport Sync Feb 08 05:23:00 volumio volumio[29624]: info: Starting Shairport Sync Feb 08 05:23:00 volumio volumio[29624]: info: Starting Shairport Sync Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:00 volumio sudo[29912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:00 volumio sudo[29914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:00 volumio sudo[29914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:00 volumio sudo[29912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:00 volumio sudo[29916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:00 volumio sudo[29916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=debug msg="completed challenge" Feb 08 05:23:00 volumio go-librespot[29895]: time="2026-02-08T05:23:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:23:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:23:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:00 volumio systemd[1]: shairport-sync.service: Consumed 4.105s CPU time. Feb 08 05:23:00 volumio volumio[29624]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 08 05:23:01 volumio sudo[29924]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 08 05:23:01 volumio sudo[29924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:01 volumio sudo[29916]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio sudo[29914]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio volumio[29624]: info: Asound.conf file unchanged, so no further update is needed Feb 08 05:23:01 volumio volumio[29624]: info: Output device has changed, restarting MPD Feb 08 05:23:01 volumio sudo[29912]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio sudo[29924]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio volumio[29624]: info: Output device has changed, restarting Shairport Sync Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:23:01 volumio sudo[29930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:23:01 volumio sudo[29930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:01 volumio sudo[29929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:23:01 volumio sudo[29929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:01 volumio sudo[29929]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 05:23:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 05:23:01 volumio systemd[1]: mpd.service: Consumed 1.691s CPU time. Feb 08 05:23:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 05:23:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 05:23:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 05:23:01 volumio volumio[29624]: info: Shairport-Sync Started Feb 08 05:23:01 volumio volumio[29624]: Error adding Membership: Error: addMembership EINVAL Feb 08 05:23:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 05:23:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 05:23:01 volumio volumio[29624]: info: MPD Permissions set Feb 08 05:23:01 volumio volumio[29624]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 08 05:23:01 volumio volumio[29624]: info: Shairport-Sync Started Feb 08 05:23:01 volumio volumio[29624]: info: Shairport-Sync Started Feb 08 05:23:01 volumio sudo[29958]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 08 05:23:01 volumio sudo[29958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:01 volumio sudo[29958]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:01 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::volumioGetState Feb 08 05:23:01 volumio volumio[29624]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:01 volumio volumio[29624]: info: Starting Shairport Sync Feb 08 05:23:01 volumio sudo[29966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 08 05:23:01 volumio sudo[29966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:01 volumio systemd[1]: Reloading. Feb 08 05:23:01 volumio volumio[29624]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Feb 08 05:23:01 volumio sudo[29969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:01 volumio volumio[29624]: info: Spotify Successfully logged in Feb 08 05:23:01 volumio sudo[29969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:01 volumio sudo[29955]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 05:23:01 volumio sudo[29955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:01 volumio volumio[29624]: info: [1770549781850] CoreMusicLibrary::Adding element Spotify Feb 08 05:23:01 volumio sudo[29955]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:01 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source SoundCloud Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source YouTube2 Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source YouTube Music Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source Podcast Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source Volusonic Feb 08 05:23:01 volumio volumio[29624]: Cannot find translation for source Spotify Feb 08 05:23:02 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:23:02 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:02 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:02 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:02 volumio volumio[29624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:03 volumio volumio[29624]: info: go-librespot daemon successfully initialized Feb 08 05:23:04 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 05:23:04 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 05:23:04 volumio sudo[29966]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:04 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:04 volumio sudo[29994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:04 volumio sudo[29994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 08 05:23:04 volumio sudo[29994]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:04 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 08 05:23:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:04 volumio go-librespot[29996]: go-librespot daemon starting... Feb 08 05:23:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:23:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:23:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:04 volumio systemd[1]: shairport-sync.service: Consumed 1.533s CPU time. Feb 08 05:23:04 volumio go-librespot[29997]: time="2026-02-08T05:23:04-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:04 volumio go-librespot[29997]: time="2026-02-08T05:23:04-06:00" level=debug msg="app state loaded" Feb 08 05:23:04 volumio go-librespot[29997]: time="2026-02-08T05:23:04-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:04 volumio go-librespot[29997]: time="2026-02-08T05:23:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:04 volumio sudo[29969]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:05 volumio volumio[29624]: info: Shairport-Sync Started Feb 08 05:23:05 volumio sudo[30004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 08 05:23:05 volumio sudo[30004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:05 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 05:23:05 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 08 05:23:05 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:05 volumio sudo[30004]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=info msg="zeroconf server listening on port 37335" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="obtained new client token: AAAa4WL0GkZH8mNjCzGlvbBax/2EZHyKs1WnRL3svLOtTBFnoEqJdmWfr+sFJp2JLqV3kUTs53Ubyn5SPmh3ajnXx80B/CjwUULrSmhu0xt4AoZihBY4j/YJFn4NOqNSMBmdtAxE+3pXWY6EaHF/yuLH3zwRo5tZUevaFhJMSgUBZNVOodnrr8yyLN3wGP3DtYvmtx41F7mmI4DwvQriCdUx9izsa8FWMhZiAJ1KC/F4anTcW3n/dqEP6Q==" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=debug msg="completed challenge" Feb 08 05:23:05 volumio go-librespot[29997]: time="2026-02-08T05:23:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:05 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:05 volumio sudo[30037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:05 volumio sudo[30037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:05 volumio sudo[30037]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:05 volumio volumio[29624]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 08 05:23:06 volumio sudo[30041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 08 05:23:06 volumio sudo[30041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:06 volumio sudo[30041]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:06 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 08 05:23:06 volumio sudo[30044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 08 05:23:06 volumio sudo[30044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:06 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 05:23:06 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 05:23:06 volumio sudo[30044]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:06 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:06 volumio volumio[29624]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:06 volumio volumio[29624]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:23:06 volumio sudo[30051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:06 volumio sudo[30051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:06 volumio sudo[30051]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:07 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:07 volumio sudo[30054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:07 volumio sudo[30054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:07 volumio sudo[30054]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:07 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:07 volumio sudo[30057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:07 volumio sudo[30057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:07 volumio mpd[29992]: 2026-02-08T05:23:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 05:23:07 volumio sudo[30057]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 05:23:08 volumio sudo[29930]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:08 volumio sudo[29840]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:08 volumio sudo[29850]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:08 volumio volumio[29624]: error: MPD error: The expression evaluated to a falsy value: Feb 08 05:23:08 volumio volumio[29624]: assert.ok(self.idling) Feb 08 05:23:08 volumio volumio[29624]: error: The expression evaluated to a falsy value: Feb 08 05:23:08 volumio volumio[29624]: assert.ok(self.idling) Feb 08 05:23:08 volumio volumio[29624]: error: MPD error: The expression evaluated to a falsy value: Feb 08 05:23:08 volumio volumio[29624]: assert.ok(self.idling) Feb 08 05:23:08 volumio volumio[29624]: error: The expression evaluated to a falsy value: Feb 08 05:23:08 volumio volumio[29624]: assert.ok(self.idling) Feb 08 05:23:08 volumio volumio[29624]: info: MPD running with PID29992 Feb 08 05:23:08 volumio volumio[29624]: ,establishing connection Feb 08 05:23:08 volumio volumio[29624]: error: updateQueue error: null Feb 08 05:23:08 volumio volumio[29624]: error: updateQueue error: null Feb 08 05:23:08 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:08 volumio sudo[30065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:08 volumio sudo[30065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:08 volumio sudo[30065]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:08 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:23:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 08 05:23:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:08 volumio go-librespot[30068]: go-librespot daemon starting... Feb 08 05:23:08 volumio go-librespot[30069]: time="2026-02-08T05:23:08-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:08 volumio go-librespot[30069]: time="2026-02-08T05:23:08-06:00" level=debug msg="app state loaded" Feb 08 05:23:08 volumio go-librespot[30069]: time="2026-02-08T05:23:08-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:08 volumio go-librespot[30069]: time="2026-02-08T05:23:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=info msg="zeroconf server listening on port 40489" Feb 08 05:23:09 volumio volumio[29624]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:09 volumio sudo[30078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:09 volumio sudo[30078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:09 volumio sudo[30078]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:09 volumio volumio[29624]: info: Completed starting Core Plugins Feb 08 05:23:09 volumio volumio[29624]: info: ------------------------------------------- Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="obtained new client token: AACdJ+g7QChgMxubt0D9YJ8r1RuAdnBOFer+GHlTCFDkccZEfRibQHQZX2yiPRO4VAtcQTnbP2ILDJjaIX9KcY1wf6PvD5Swo7SDTfbNVqglomAVeywe+qjjl0sImUCdCFIhIIdDR96x5ajRmrtwVj6QTIBE9SSr7QYoPNGHF1lI78KiWBdFA4uTebHDtZWR77Cf4t5wokIoRlLXDudERaON5i8yCY23Zy+9iKlj0+xnrDY7jhccFzM=" Feb 08 05:23:09 volumio volumio[29624]: info: ----- MyVolumio plugins startup ---- Feb 08 05:23:09 volumio volumio[29624]: info: ------------------------------------------- Feb 08 05:23:09 volumio volumio[29624]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=debug msg="completed challenge" Feb 08 05:23:09 volumio go-librespot[30069]: time="2026-02-08T05:23:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:09 volumio volumio[29624]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:09 volumio volumio[29624]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:23:11 volumio sudo[30081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:23:11 volumio sudo[30081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:11 volumio sudo[30083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:23:11 volumio sudo[30083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:11 volumio sudo[30086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:23:11 volumio sudo[30086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 08 05:23:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 08 05:23:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 08 05:23:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 05:23:11 volumio sudo[30083]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:11 volumio mpd_monitor.sh[30089]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 05:23:11 volumio sudo[30081]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:11 volumio volumio[29624]: info: Successfully started MPD Monitor Feb 08 05:23:11 volumio sudo[30086]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:11 volumio volumio[29624]: info: Successfully started MPD Monitor Feb 08 05:23:11 volumio volumio[29624]: info: Successfully started MPD Monitor Feb 08 05:23:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 08 05:23:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:12 volumio go-librespot[30093]: go-librespot daemon starting... Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="app state loaded" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:12 volumio volumio[29624]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="new websocket client" Feb 08 05:23:12 volumio volumio[29624]: info: Connection to go-librespot Websocket established Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=info msg="zeroconf server listening on port 35595" Feb 08 05:23:12 volumio go-librespot[30094]: time="2026-02-08T05:23:12-06:00" level=debug msg="obtained new client token: AACOQHqRVy96orkeO6VxcN6gUNhNCOR3m+Ng7DBJXkYB+/wg3iAARL85SRtWiOrHbzFMR+6ValJuBmNYsEOjmLIuR6yS07Ej5by5Y3rIz0wqNwhLOVrE6DSUXGWKYWbqqhWMvezfTarEwPBAWRmDLaSyUNPN46o1xDvg7sp3WcqaNCq3DUnTr2j/L9frkTQaNsoeMrUsSbv2IFgAKmch2IHbV42xZ4YZe2S9ZhIfsd+xwsHzdBjRKFrrFg==" Feb 08 05:23:13 volumio go-librespot[30094]: time="2026-02-08T05:23:13-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 08 05:23:13 volumio go-librespot[30094]: time="2026-02-08T05:23:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 08 05:23:13 volumio go-librespot[30094]: time="2026-02-08T05:23:13-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:13 volumio go-librespot[30094]: time="2026-02-08T05:23:13-06:00" level=debug msg="completed challenge" Feb 08 05:23:13 volumio go-librespot[30094]: time="2026-02-08T05:23:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:13 volumio volumio[29624]: info: Connection to go-librespot Websocket closed Feb 08 05:23:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:14 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:23:15 volumio volumio[29624]: info: Getting Spotify volume Feb 08 05:23:16 volumio volumio[29624]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 05:23:16 volumio volumio[29624]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:23:16 volumio volumio[29624]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 08 05:23:16 volumio volumio[29624]: errno: -111, Feb 08 05:23:16 volumio volumio[29624]: code: 'ECONNREFUSED', Feb 08 05:23:16 volumio volumio[29624]: syscall: 'connect', Feb 08 05:23:16 volumio volumio[29624]: address: '127.0.0.1', Feb 08 05:23:16 volumio volumio[29624]: port: 9879, Feb 08 05:23:16 volumio volumio[29624]: response: undefined Feb 08 05:23:16 volumio volumio[29624]: } Feb 08 05:23:16 volumio volumio[29624]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 05:23:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 08 05:23:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:16 volumio go-librespot[30127]: go-librespot daemon starting... Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=debug msg="app state loaded" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=info msg="zeroconf server listening on port 42029" Feb 08 05:23:16 volumio go-librespot[30129]: time="2026-02-08T05:23:16-06:00" level=debug msg="obtained new client token: AAAARFHOHzsMibSkXE+JippLSXTbNeAGPUMJw+0/GEFHjP7dUilU6cSCQ3+/3htRbRe2Xc1Ua2Ev7gjLsI8o+AS9qjk3Tx5pWLe5YM+PY5Wd22xMRKDusRbF9CP65SSHc+cuJktLo9uQ6doD0u2b/Ml+W3/4tgqxLjLnaZrcKQhjl5FhgZ5cIYM8w8pgKm+lLsBWo7lc9eC2GMcUOH6l4voO0OJ4tCFki6tZg/U5HG7nBv2tXcl4m0hXIg==" Feb 08 05:23:17 volumio go-librespot[30129]: time="2026-02-08T05:23:17-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:17 volumio go-librespot[30129]: time="2026-02-08T05:23:17-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:17 volumio go-librespot[30129]: time="2026-02-08T05:23:17-06:00" level=debug msg="completed challenge" Feb 08 05:23:17 volumio go-librespot[30129]: time="2026-02-08T05:23:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:17 volumio sudo[30139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 05:22' Feb 08 05:23:17 volumio sudo[30139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:17 volumio sudo[30139]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:18 volumio volumio-remote-updater[751]: [2026-02-08 05:23:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 05:23:18 volumio volumio-remote-updater[751]: [2026-02-08 05:23:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 05:23:18 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:18 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 05:23:18 volumio systemd[1]: volumio.service: Consumed 44.288s CPU time. Feb 08 05:23:18 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 05:23:18 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 05:23:18 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 722. Feb 08 05:23:18 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 05:23:18 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 05:23:18 volumio systemd[1]: volumio.service: Consumed 44.288s CPU time. Feb 08 05:23:18 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 05:23:18 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 05:23:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Feb 08 05:23:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:20 volumio go-librespot[30172]: go-librespot daemon starting... Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="app state loaded" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:20 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:20 volumio volumio[30157]: info: ----- Volumio3 ---- Feb 08 05:23:20 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:20 volumio volumio[30157]: info: ----- System startup ---- Feb 08 05:23:20 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=info msg="zeroconf server listening on port 39487" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="obtained new client token: AACyw7zooWsLyfb25Eb4RuQLYVPetfJZ+de0umEPnZeE1ryOF/TVdLU9AZD0aYuXklj4UtSeBWV80stSVMM2e8iBBNx+WvVpTrks1E7AYY367ksJa8rWCPRpymh8WTv6zwBH9iAosHv3KeHf+S7gkV5eI5X0rjQ+iYB0lUtjQ3Mzp4hOKsCEdlfqDzwc0dGQgdckYvYanWyHsuPXKlnS3Vbfu+Qd2nXFl3mXz5l+Yw2xbxiHy3dyx/M8cA==" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=debug msg="completed challenge" Feb 08 05:23:20 volumio go-librespot[30173]: time="2026-02-08T05:23:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:21 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:23:21 volumio volumio[30157]: info: MYVOLUMIO Environment detected Feb 08 05:23:21 volumio volumio[30157]: info: Plugin folders cleanup Feb 08 05:23:21 volumio volumio[30157]: info: Scanning into folder /volumio/app/plugins/ Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category audio_interface Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category miscellanea Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category music_service Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category plugins.json Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category system_controller Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category user_interface Feb 08 05:23:21 volumio volumio[30157]: info: Scanning into folder /data/plugins/ Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category audio_interface Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category music_service Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category system_controller Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category system_hardware Feb 08 05:23:21 volumio volumio[30157]: info: Scanning category user_interface Feb 08 05:23:21 volumio volumio[30157]: info: Plugin folders cleanup completed Feb 08 05:23:21 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:21 volumio volumio[30157]: info: ----- Core plugins startup ---- Feb 08 05:23:21 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:21 volumio volumio[30157]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 05:23:21 volumio volumio[30157]: info: Adding plugin upnp to MyMusic Plugins Feb 08 05:23:21 volumio volumio[30157]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 05:23:21 volumio volumio[30157]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 05:23:21 volumio volumio[30157]: info: Loading plugins from folder /data/plugins/ Feb 08 05:23:21 volumio volumio[30157]: info: Loading plugin "system"... Feb 08 05:23:21 volumio volumio[30157]: info: Loading plugin "appearance"... Feb 08 05:23:22 volumio volumio[30157]: info: Loading plugin "network"... Feb 08 05:23:22 volumio volumio[30157]: info: Refreshing Cached IP Addresses Feb 08 05:23:22 volumio sudo[30194]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 05:23:22 volumio sudo[30194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:22 volumio sudo[30194]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:22 volumio sudo[30196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 05:23:22 volumio sudo[30196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:22 volumio volumio[30157]: info: Loading plugin "services"... Feb 08 05:23:22 volumio sudo[30196]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:22 volumio volumio[30157]: info: Loading plugin "alsa_controller"... Feb 08 05:23:22 volumio sudo[30204]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 05:23:22 volumio sudo[30204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:22 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 05:23:22 volumio volumio[30157]: info: Loading plugin "wizard"... Feb 08 05:23:22 volumio volumio[30157]: info: Loading plugin "networkfs"... Feb 08 05:23:22 volumio volumio[30157]: info: Starting Udev Watcher for removable devices Feb 08 05:23:22 volumio volumio[30157]: info: Ignoring mount for partition: boot Feb 08 05:23:22 volumio volumio[30157]: info: Ignoring mount for partition: volumio Feb 08 05:23:22 volumio volumio[30157]: info: Ignoring mount for partition: volumio_data Feb 08 05:23:22 volumio volumio[30157]: info: Mounting Device Wikipedia Feb 08 05:23:22 volumio sudo[30233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 08 05:23:22 volumio sudo[30233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:22 volumio sudo[30233]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:22 volumio volumio[30157]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 05:23:22 volumio volumio[30157]: dmesg(1) may have more information after failed mount system call. Feb 08 05:23:22 volumio volumio[30157]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 08 05:23:22 volumio volumio[30157]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 05:23:22 volumio volumio[30157]: dmesg(1) may have more information after failed mount system call. Feb 08 05:23:22 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 05:23:22 volumio volumio[30157]: info: Loading plugin "volumio_command_line_client"... Feb 08 05:23:23 volumio volumio[30157]: info: Loading plugin "upnp"... Feb 08 05:23:23 volumio volumio[30157]: info: [1770549803010] Starting Upmpd Daemon Feb 08 05:23:23 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 05:23:23 volumio volumio[30157]: info: Loading plugin "my_music"... Feb 08 05:23:23 volumio volumio[30157]: info: Loading plugin "mpd"... Feb 08 05:23:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:23:23 volumio sudo[30204]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:23 volumio volumio-remote-updater[751]: [2026-02-08 05:23:23] [connect] Successful connection Feb 08 05:23:23 volumio volumio[30157]: info: Loading plugin "upnp_browser"... Feb 08 05:23:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Feb 08 05:23:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:24 volumio go-librespot[30235]: go-librespot daemon starting... Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="app state loaded" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=info msg="zeroconf server listening on port 35019" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="obtained new client token: AAAk3ZLGrqaKQWELQ8KU57ODj76cq8o6rXq2Xq++xCYHAz761gH9quc/KS37p3rQpRSm3TdU9HgPb6dYS9phlnvN4+BEMsqAz4kL1gT+mGK1EQn9UP6Oul1NLh8SWRXmg8pSHqORqL7l15Ct/DLxKwbxdqeOrxaEPsNFcPXm+XtT4Ckndd2E2bvIq7wDa1dlKwoKE1L2O1n0FVyQOS5bcXhj4L/cphAqJtHo5VvqrLoaL8yxhsqSbcKasA==" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=debug msg="completed challenge" Feb 08 05:23:24 volumio go-librespot[30236]: time="2026-02-08T05:23:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "alarm-clock"... Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "airplay_emulation"... Feb 08 05:23:26 volumio volumio[30157]: info: Starting Shairport Sync Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "last_100"... Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "webradio"... Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "i2s_dacs"... Feb 08 05:23:26 volumio volumio[30157]: info: I2S DAC not set, start Auto-detection Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "volumiodiscovery"... Feb 08 05:23:26 volumio volumio[30157]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 05:23:26 volumio volumio[30157]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:23:26 volumio volumio[30157]: *** WARNING *** For more information see Feb 08 05:23:26 volumio volumio[30157]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 05:23:26 volumio volumio[30157]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:23:26 volumio volumio[30157]: *** WARNING *** For more information see Feb 08 05:23:26 volumio node[30157]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 05:23:26 volumio node[30157]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:23:26 volumio node[30157]: *** WARNING *** For more information see Feb 08 05:23:26 volumio node[30157]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 05:23:26 volumio node[30157]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:23:26 volumio node[30157]: *** WARNING *** For more information see Feb 08 05:23:26 volumio volumio[30157]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 05:23:26 volumio volumio[30157]: info: Discovery: Started advertising with name: Volumio Feb 08 05:23:26 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 05:23:26 volumio volumio[30157]: info: Loading plugin "bandcamp"... Feb 08 05:23:27 volumio volumio[30157]: info: Plugin calmradio is not enabled Feb 08 05:23:27 volumio volumio[30157]: info: Loading plugin "soundcloud"... Feb 08 05:23:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 08 05:23:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:28 volumio go-librespot[30245]: go-librespot daemon starting... Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="app state loaded" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:28 volumio volumio[30157]: info: Loading plugin "spop"... Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=info msg="zeroconf server listening on port 43677" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="obtained new client token: AACA67adqU01Adn/IifBz6E3mOQ6Mxk4G74dDkL0j+qSO8kIdLxt00CMClIngWwKrXcMI7sRaMrirP+kL1f4b5RBGtTHewRCqOoqczCocR7W9WIumbiKHnVnMQAfAX+YSKXG1ifRs1f1plOwRpcHg0b5FxN94562+AQJdIpSB9ZDJ98upU97K6EdOxA+oq0ecckYsrVWHUPNd3Kcf28mdqR5I1jw0vzZ97+2vkJrhG+R1L9HhWE55Db67Q==" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=debug msg="completed challenge" Feb 08 05:23:28 volumio go-librespot[30246]: time="2026-02-08T05:23:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:29 volumio volumio[30157]: info: Loading plugin "squeezelite_mc"... Feb 08 05:23:29 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:23:30 volumio volumio[30157]: info: Loading plugin "youtube2"... Feb 08 05:23:30 volumio volumio[30157]: info: Loading plugin "ytcr"... Feb 08 05:23:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 08 05:23:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:31 volumio go-librespot[30257]: go-librespot daemon starting... Feb 08 05:23:31 volumio go-librespot[30258]: time="2026-02-08T05:23:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:31 volumio go-librespot[30258]: time="2026-02-08T05:23:31-06:00" level=debug msg="app state loaded" Feb 08 05:23:31 volumio go-librespot[30258]: time="2026-02-08T05:23:31-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:31 volumio go-librespot[30258]: time="2026-02-08T05:23:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "ytmusic"... Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=info msg="zeroconf server listening on port 33489" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="obtained new client token: AAA+dyRFzuESNednda0R/DPMRy1aaAtLj15qxgJI8FjMwsQlkPTIXT1w7exnTo1+v3IgMcB1Zx8BY6r0rz87UA4aJ7ylKT+BU2uf/BH613X9T3yrkQOwLgHIYjEHFTZd7+ggVs6bYk62+B9s7NURT4dQG6KYjTrwjI3xpJwJ/kwyfifOk7rMdQtqeb0nmkxmI/Xt+tYcG16mUWLQS7IuCiqro5RoVJZT5GnijK6XuCvmcWoPh0Zitx8=" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=debug msg="completed challenge" Feb 08 05:23:32 volumio go-librespot[30258]: time="2026-02-08T05:23:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:32 volumio volumio[30157]: info: Plugin now_playing is not enabled Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "outputs"... Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "albumart"... Feb 08 05:23:32 volumio volumio[30157]: info: Plugin example_plugin is not enabled Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "inputs"... Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "updater_comm"... Feb 08 05:23:32 volumio volumio[30157]: info: Plugin mpdemulation is not enabled Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "rest_api"... Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "websocket"... Feb 08 05:23:32 volumio volumio[30157]: info: Starting Socket.io Server version 1.7.4 Feb 08 05:23:32 volumio volumio[30157]: info: Plugin fusiondsp is not enabled Feb 08 05:23:32 volumio volumio[30157]: info: Plugin mpdoutput is not enabled Feb 08 05:23:32 volumio volumio[30157]: info: Plugin RoonBridge is not enabled Feb 08 05:23:32 volumio volumio[30157]: info: Loading plugin "podcast"... Feb 08 05:23:33 volumio volumio[30157]: info: ControllerPodcast::constructor Feb 08 05:23:33 volumio volumio[30157]: info: Loading plugin "volusonic"... Feb 08 05:23:33 volumio volumio[30265]: Forking 3 albumart workers Feb 08 05:23:33 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:23:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 08 05:23:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:35 volumio go-librespot[30320]: go-librespot daemon starting... Feb 08 05:23:35 volumio go-librespot[30321]: time="2026-02-08T05:23:35-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:35 volumio go-librespot[30321]: time="2026-02-08T05:23:35-06:00" level=debug msg="app state loaded" Feb 08 05:23:35 volumio go-librespot[30321]: time="2026-02-08T05:23:35-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:35 volumio go-librespot[30321]: time="2026-02-08T05:23:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:35 volumio volumio-remote-updater[751]: [2026-02-08 05:23:35] [connect] Successful connection Feb 08 05:23:36 volumio volumio[30289]: Starting albumart workers Feb 08 05:23:36 volumio volumio[30157]: info: Applying required configuration parameters for plugin volusonic Feb 08 05:23:36 volumio volumio[30157]: info: Loading plugin "backup_restore"... Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=info msg="zeroconf server listening on port 42393" Feb 08 05:23:36 volumio volumio[30290]: Starting albumart workers Feb 08 05:23:36 volumio volumio[30291]: Starting albumart workers Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="obtained new client token: AADV/pVeIy+r6iIKzTEdkiCjpZn5zMg03td9Zrpf9KS+F3u92AnF2o0qZ2mVeCGXtHI0ih9a5WYJg0lSC3qEljCEiyfyucdDyKuRnNymoBXvrhlhLRStwv035wSV0ibGz3hsls5gs5+MHxPFkiu0juHDLpNvuwicSyjsX2np+vvCKgCoKe+rQmM5sHlbgtOmuuvFJCf9aOfM3PzANeW8J8GyQkrjCe/QJ/f7LFRELqb+H8wzMFlgnqVtjA==" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=debug msg="completed challenge" Feb 08 05:23:36 volumio go-librespot[30321]: time="2026-02-08T05:23:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:36 volumio volumio[30157]: info: Applying required configuration parameters for plugin backup_restore Feb 08 05:23:36 volumio volumio[30157]: info: Plugin rpi_eeprom_config is not enabled Feb 08 05:23:36 volumio volumio[30157]: info: Plugin rpi_eeprom_updater is not enabled Feb 08 05:23:36 volumio volumio[30157]: info: Loading plugin "scheduledrestart"... Feb 08 05:23:36 volumio volumio[30157]: info: Applying required configuration parameters for plugin scheduledrestart Feb 08 05:23:36 volumio volumio[30157]: info: Plugin Bluetoothremote is not enabled Feb 08 05:23:36 volumio volumio[30157]: info: Plugin music_services_shield is not enabled Feb 08 05:23:36 volumio volumio[30157]: info: Loading plugin "Systeminfo"... Feb 08 05:23:37 volumio volumio[30157]: info: Plugin peppymeterbasic is not enabled Feb 08 05:23:37 volumio volumio[30157]: info: Loading plugin "peppyspectrum"... Feb 08 05:23:37 volumio volumio[30157]: info: Loading i18n strings for locale en Feb 08 05:23:37 volumio volumio[30157]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 05:23:37 volumio volumio[30157]: Updating browse sources language Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::initPlayerControls Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:37 volumio volumio[30157]: Express server listening on port 3000 Feb 08 05:23:37 volumio volumio[30157]: [Metrics] WebUI: 17s 722.24ms Feb 08 05:23:37 volumio volumio[30157]: info: CoreStateMachine::resetVolumioState Feb 08 05:23:37 volumio volumio[30157]: info: CoreStateMachine::getcurrentVolume Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:37 volumio sudo[30352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 05:23:37 volumio sudo[30352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:37 volumio sudo[30352]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:37 volumio sudo[30354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 05:23:37 volumio sudo[30354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:37 volumio sudo[30354]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:37 volumio volumio[30157]: info: Volumio Network Manager: Network status updated: 1 Feb 08 05:23:37 volumio volumio[30157]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:23:37 volumio volumio[30157]: info: CoreStateMachine::pushState Feb 08 05:23:37 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::volumioPushState Feb 08 05:23:37 volumio volumio[30157]: info: CoreStateMachine::updateTrackBlock Feb 08 05:23:37 volumio volumio[30157]: info: CorePlayQueue::getTrackBlock Feb 08 05:23:37 volumio volumio[30157]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:23:37 volumio volumio-remote-updater[751]: [2026-02-08 05:23:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770549815 101 Feb 08 05:23:38 volumio volumio[30157]: 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 08 05:23:38 volumio volumio[30157]: info: Reloading queue from file Feb 08 05:23:38 volumio volumio[30157]: info: CoreStateMachine::setRepeat null single undefined Feb 08 05:23:38 volumio volumio[30157]: info: CoreStateMachine::pushState Feb 08 05:23:38 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioPushState Feb 08 05:23:38 volumio volumio[30157]: info: CoreStateMachine::setRandom null Feb 08 05:23:38 volumio volumio[30157]: info: CoreStateMachine::pushState Feb 08 05:23:38 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioPushState Feb 08 05:23:38 volumio volumio[30157]: info: Setting Device type: Raspberry PI Feb 08 05:23:38 volumio volumio[30157]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:23:38 volumio volumio[30157]: info: CoreStateMachine::pushState Feb 08 05:23:38 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioPushState Feb 08 05:23:38 volumio volumio[30157]: info: Completed loading Core Plugins Feb 08 05:23:38 volumio volumio[30157]: info: Preparing to generate the ALSA configuration file Feb 08 05:23:38 volumio sudo[30368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 05:23:38 volumio sudo[30368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:38 volumio volumio[30157]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 05:23:38 volumio volumio[30157]: info: Discovery: Found device Volumio Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioGetState Feb 08 05:23:38 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:38 volumio volumio[30157]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 05:23:38 volumio volumio[30157]: info: Discovery: Found device Volumio Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioGetState Feb 08 05:23:38 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:38 volumio sudo[30368]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:38 volumio volumio[30157]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 05:23:38 volumio volumio[30157]: info: Reading ALSA contributions from plugins. Feb 08 05:23:38 volumio volumio[30157]: info: Upmpdcli Daemon Started Feb 08 05:23:38 volumio volumio[30157]: info: Asound.conf file unchanged, so no further update is needed Feb 08 05:23:38 volumio volumio[30157]: info: Output device has changed, restarting MPD Feb 08 05:23:38 volumio volumio[30157]: info: Output device has changed, restarting Shairport Sync Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:38 volumio sudo[30371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:23:38 volumio sudo[30371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:38 volumio sudo[30371]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:38 volumio sudo[30373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:23:38 volumio sudo[30373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:38 volumio volumio[30157]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:23:38 volumio volumio[30157]: info: ___________ START PLUGINS ___________ Feb 08 05:23:38 volumio volumio[30157]: info: ControllerMpd::onStart: Initializing MPD Feb 08 05:23:38 volumio volumio[30157]: info: Creating MPD Configuration file Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818322] CoreMusicLibrary::Adding element Media Servers Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 05:23:38 volumio sudo[30381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:23:38 volumio sudo[30381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:38 volumio sudo[30383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:23:38 volumio sudo[30381]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:38 volumio sudo[30383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:38 volumio volumio[30157]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 05:23:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 05:23:38 volumio systemd[1]: mpd.service: Consumed 4.307s CPU time. Feb 08 05:23:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 05:23:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 05:23:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 05:23:38 volumio volumio[30157]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818458] CoreMusicLibrary::Adding element Last_100 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818462] CoreMusicLibrary::Adding element Webradio Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 05:23:38 volumio volumio[30157]: info: Initializing BBC Radios Feb 08 05:23:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818541] CoreMusicLibrary::Adding element Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818559] CoreMusicLibrary::Adding element SoundCloud Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: info: Creating Spotify config file Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:38 volumio sudo[30409]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 05:23:38 volumio sudo[30409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 05:23:38 volumio sudo[30409]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:38 volumio volumio[30157]: info: [squeezelite_mc] Starting proxy server... Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818907] CoreMusicLibrary::Adding element YouTube2 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818925] CoreMusicLibrary::Adding element YouTube Music Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube Music Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818937] CoreMusicLibrary::Adding element Podcast Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube Music Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Podcast Feb 08 05:23:38 volumio volumio[30157]: info: Loading i18n strings for locale en Feb 08 05:23:38 volumio volumio[30157]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 05:23:38 volumio volumio[30157]: Updating browse sources language Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube Music Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Podcast Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube Music Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Podcast Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:38 volumio volumio[30157]: info: [1770549818985] CoreMusicLibrary::Adding element Volusonic Feb 08 05:23:38 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source YouTube Music Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Podcast Feb 08 05:23:38 volumio volumio[30157]: Cannot find translation for source Volusonic Feb 08 05:23:39 volumio volumio[30157]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 05:23:39 volumio volumio[30157]: info: Volumio Calling Home Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Proxy server started on port 41457 Feb 08 05:23:39 volumio volumio[30157]: info: Preparing to generate the ALSA configuration file Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:23:39 volumio volumio[30157]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 05:23:39 volumio volumio[30157]: info: Reading ALSA contributions from plugins. Feb 08 05:23:39 volumio volumio[30157]: info: MPD Permissions set Feb 08 05:23:39 volumio volumio[30157]: info: MPD Permissions set Feb 08 05:23:39 volumio volumio[30157]: info: Spotify config file written Feb 08 05:23:39 volumio sudo[30419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 05:23:39 volumio sudo[30419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:39 volumio volumio[30157]: 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 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: No need to fix Spotify hosts Feb 08 05:23:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:39 volumio go-librespot[30426]: go-librespot daemon starting... Feb 08 05:23:39 volumio sudo[30419]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=debug msg="app state loaded" Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:39 volumio volumio[30157]: 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 08 05:23:39 volumio volumio[30157]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:23:39 volumio volumio[30157]: info: CoreStateMachine::pushState Feb 08 05:23:39 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::volumioPushState Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Server discovery started Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Player finder started Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 08 05:23:39 volumio volumio[30157]: info: Volumio called home Feb 08 05:23:39 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=info msg="zeroconf server listening on port 45981" Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 08 05:23:39 volumio volumio[30157]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 08 05:23:39 volumio volumio[30157]: info: Starting Shairport Sync Feb 08 05:23:39 volumio volumio[30157]: info: Starting Shairport Sync Feb 08 05:23:39 volumio volumio[30157]: info: Starting Shairport Sync Feb 08 05:23:39 volumio sudo[30445]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 08 05:23:39 volumio sudo[30445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:39 volumio sudo[30445]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:39 volumio volumio[30157]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 05:23:39 volumio volumio[30157]: SPOTIFY: BQBdSdPxiNhI-dfkRIecRKpl_BdYul59r5z97I56JQ5jmigYNIjQXl8b1eSgY92-20jp9-3UcjojnuoIYM3EReCdSfY4Sera_l5IPN0IQLhz0B7x-QyjMAN3A_H9I-DINtNfS2Y2suP4sT-Z59T6lB2BiCon_RHI_15X0X9K9i2pCl2qr0WeY5aV69BUVbAEAaCicvgVc3ikDYBpALep021ng35t_IWA0jGgQTI Feb 08 05:23:39 volumio volumio[30157]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 05:23:39 volumio volumio[30157]: info: New Spotify access token = BQBdSdPxiNhI-dfkRIecRKpl_BdYul59r5z97I56JQ5jmigYNIjQXl8b1eSgY92-20jp9-3UcjojnuoIYM3EReCdSfY4Sera_l5IPN0IQLhz0B7x-QyjMAN3A_H9I-DINtNfS2Y2suP4sT-Z59T6lB2BiCon_RHI_15X0X9K9i2pCl2qr0WeY5aV69BUVbAEAaCicvgVc3ikDYBpALep021ng35t_IWA0jGgQTI Feb 08 05:23:39 volumio volumio[30157]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 05:23:39 volumio go-librespot[30432]: time="2026-02-08T05:23:39-06:00" level=debug msg="obtained new client token: AAAKVbdBPJe1BICXj63Mmi9JYoj34mavP0xCqnevH9fCbXNX6vNTCxMx6g133Mirqm8Qgw/xxbQMtrGlPfBPP6kAGGzxAlEwD0XZKpsGTyOsBISmtzBt3NR3P4fCrpXI16oLCagDeUNLLduBKf55Baqkje2D+LBcYyLMPLWv3bWatqtO5y8LuFiZj5q2KqBjbQF61yu2nxB0iHht+gSHEX7BvR7F1OmmLr755P3C7bsvswdHw+rSN6LStg==" Feb 08 05:23:39 volumio sudo[30447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:39 volumio sudo[30452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:39 volumio sudo[30452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:39 volumio sudo[30447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:39 volumio sudo[30449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:40 volumio sudo[30449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio go-librespot[30432]: time="2026-02-08T05:23:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:23:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:23:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:40 volumio systemd[1]: shairport-sync.service: Consumed 2.013s CPU time. Feb 08 05:23:40 volumio go-librespot[30432]: time="2026-02-08T05:23:40-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:40 volumio go-librespot[30432]: time="2026-02-08T05:23:40-06:00" level=debug msg="completed challenge" Feb 08 05:23:40 volumio go-librespot[30432]: time="2026-02-08T05:23:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:40 volumio sudo[30447]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:40 volumio volumio[30157]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 08 05:23:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:40 volumio sudo[30452]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio volumio[30157]: info: Asound.conf file unchanged, so no further update is needed Feb 08 05:23:40 volumio volumio[30157]: info: Output device has changed, restarting MPD Feb 08 05:23:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:23:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:23:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:40 volumio volumio[30157]: info: Output device has changed, restarting Shairport Sync Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:40 volumio sudo[30461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:23:40 volumio sudo[30461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio sudo[30449]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio sudo[30459]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 08 05:23:40 volumio sudo[30459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio sudo[30461]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio sudo[30464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:23:40 volumio sudo[30464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio volumio[30157]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:23:40 volumio sudo[30459]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 05:23:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 05:23:40 volumio systemd[1]: mpd.service: Consumed 1.660s CPU time. Feb 08 05:23:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 05:23:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 05:23:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 05:23:40 volumio volumio[30157]: info: Shairport-Sync Started Feb 08 05:23:40 volumio volumio[30157]: Error adding Membership: Error: addMembership EINVAL Feb 08 05:23:40 volumio volumio[30157]: info: Shairport-Sync Started Feb 08 05:23:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 05:23:40 volumio volumio[30157]: info: MPD Permissions set Feb 08 05:23:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 05:23:40 volumio volumio[30157]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 08 05:23:40 volumio volumio[30157]: info: Shairport-Sync Started Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio sudo[30494]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio sudo[30494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio sudo[30494]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::volumioGetState Feb 08 05:23:40 volumio volumio[30157]: info: CorePlayQueue::getTrack 0 Feb 08 05:23:40 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 08 05:23:40 volumio sudo[30502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 08 05:23:40 volumio sudo[30502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio volumio[30157]: info: Starting Shairport Sync Feb 08 05:23:40 volumio sudo[30505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:23:40 volumio sudo[30505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:40 volumio sudo[30491]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 05:23:40 volumio sudo[30491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 05:23:40 volumio systemd[1]: Reloading. Feb 08 05:23:40 volumio sudo[30491]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:40 volumio volumio[30157]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Feb 08 05:23:40 volumio volumio[30157]: info: Spotify Successfully logged in Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:23:40 volumio volumio[30157]: info: [1770549820965] CoreMusicLibrary::Adding element Spotify Feb 08 05:23:40 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source Bandcamp Discover Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source SoundCloud Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source YouTube2 Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source YouTube Music Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source Podcast Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source Volusonic Feb 08 05:23:40 volumio volumio[30157]: Cannot find translation for source Spotify Feb 08 05:23:41 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:41 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:41 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:23:41 volumio volumio[30157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:23:41 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:23:42 volumio volumio[30157]: info: go-librespot daemon successfully initialized Feb 08 05:23:43 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 05:23:43 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 05:23:43 volumio sudo[30502]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:43 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:43 volumio sudo[30541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:43 volumio sudo[30541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 08 05:23:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:44 volumio sudo[30541]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:44 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 08 05:23:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:23:44 volumio go-librespot[30543]: go-librespot daemon starting... Feb 08 05:23:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:23:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:44 volumio systemd[1]: shairport-sync.service: Consumed 1.456s CPU time. Feb 08 05:23:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:23:44 volumio sudo[30505]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:44 volumio volumio[30157]: info: Shairport-Sync Started Feb 08 05:23:44 volumio sudo[30546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 08 05:23:44 volumio sudo[30546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="app state loaded" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:44 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 05:23:44 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 08 05:23:44 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:23:44 volumio sudo[30546]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=info msg="zeroconf server listening on port 35203" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="obtained new client token: AAC8T4Co6PG9BWPU55KfMWEsqEZxbnxgThJOIt05OgAYm+HjOSy60RJAa6ZBoJs2LIK7KE2SZDzdKfS1rWYvpQYm4xPVHOp0//+kWSV+OwqaUwdRBINUdtMQ9kxxGkxA2ATWz/6Xp5z1uTnfS0rhINNknjPnjlQUlUs3SdCUmBTknbZ+vlzSXmeDoonc9eTtuxyLFbhLtDuyQeL7Wrtg9ImRh4qM26SZLJPyOEjwZMMqj/9Gp9m79h5VHQ==" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=debug msg="completed challenge" Feb 08 05:23:44 volumio go-librespot[30545]: time="2026-02-08T05:23:44-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:45 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:45 volumio sudo[30570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:45 volumio sudo[30570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:45 volumio sudo[30570]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:45 volumio volumio[30157]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 08 05:23:45 volumio sudo[30574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 08 05:23:45 volumio sudo[30574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:45 volumio sudo[30574]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:45 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 08 05:23:45 volumio sudo[30577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 08 05:23:45 volumio sudo[30577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:45 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 05:23:45 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 05:23:45 volumio sudo[30577]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:45 volumio volumio[30157]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:45 volumio volumio[30157]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:23:46 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:46 volumio sudo[30584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:46 volumio sudo[30584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:46 volumio sudo[30584]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:46 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:46 volumio sudo[30587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:46 volumio sudo[30587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:47 volumio sudo[30587]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:47 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:47 volumio sudo[30590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:47 volumio sudo[30590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:47 volumio sudo[30590]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 08 05:23:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:48 volumio go-librespot[30592]: go-librespot daemon starting... Feb 08 05:23:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="app state loaded" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:48 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:48 volumio sudo[30600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:48 volumio sudo[30600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:48 volumio sudo[30600]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=info msg="zeroconf server listening on port 41213" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="obtained new client token: AACwu3r59hV0Vzaj2W1WfCkQkeMdKtSMmNRhjA5H2pfXN7mrtv5oz/2PXgr3d4mlpLjedayMiLdXnuOT/xJyHvDpUAvjDyiclqK+XURrL6BLU3T84yCRX6px2PQKRkFT/dV5HFucVIHjEzHViPFlqBGmVU0h6CLMxbNQycqmhBYUanYJZFmv8exigIpIqU1eDfGFQB3ZA4dxGSqG15mp5on1iBf72mdv1X4eK60/MLnurHjGFGJVdR+kUQ==" Feb 08 05:23:48 volumio mpd[30539]: 2026-02-08T05:23:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=debug msg="completed challenge" Feb 08 05:23:48 volumio go-librespot[30593]: time="2026-02-08T05:23:48-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 05:23:48 volumio sudo[30464]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:48 volumio sudo[30373]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:48 volumio sudo[30383]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:48 volumio volumio[30157]: error: MPD error: The expression evaluated to a falsy value: Feb 08 05:23:48 volumio volumio[30157]: assert.ok(self.idling) Feb 08 05:23:48 volumio volumio[30157]: error: The expression evaluated to a falsy value: Feb 08 05:23:48 volumio volumio[30157]: assert.ok(self.idling) Feb 08 05:23:48 volumio volumio[30157]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:23:48 volumio volumio[30157]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:48 volumio volumio[30157]: error: MPD error: The expression evaluated to a falsy value: Feb 08 05:23:48 volumio volumio[30157]: assert.ok(self.idling) Feb 08 05:23:48 volumio volumio[30157]: error: The expression evaluated to a falsy value: Feb 08 05:23:48 volumio volumio[30157]: assert.ok(self.idling) Feb 08 05:23:48 volumio volumio[30157]: info: MPD running with PID30539 Feb 08 05:23:48 volumio volumio[30157]: ,establishing connection Feb 08 05:23:48 volumio volumio[30157]: error: updateQueue error: null Feb 08 05:23:48 volumio volumio[30157]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:23:48 volumio volumio[30157]: error: updateQueue error: null Feb 08 05:23:48 volumio sudo[30608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:23:48 volumio sudo[30608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:48 volumio sudo[30608]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:48 volumio volumio[30157]: info: Completed starting Core Plugins Feb 08 05:23:48 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:48 volumio volumio[30157]: info: ----- MyVolumio plugins startup ---- Feb 08 05:23:48 volumio volumio[30157]: info: ------------------------------------------- Feb 08 05:23:48 volumio volumio[30157]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 05:23:50 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:23:51 volumio sudo[30615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:23:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 08 05:23:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:51 volumio sudo[30615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:51 volumio sudo[30617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:23:51 volumio sudo[30617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:51 volumio sudo[30619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:23:51 volumio sudo[30619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:51 volumio go-librespot[30621]: go-librespot daemon starting... Feb 08 05:23:51 volumio go-librespot[30624]: time="2026-02-08T05:23:51-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:51 volumio go-librespot[30624]: time="2026-02-08T05:23:51-06:00" level=debug msg="app state loaded" Feb 08 05:23:51 volumio go-librespot[30624]: time="2026-02-08T05:23:51-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:51 volumio volumio[30157]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:51 volumio go-librespot[30624]: time="2026-02-08T05:23:51-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:51 volumio go-librespot[30624]: time="2026-02-08T05:23:51-06:00" level=debug msg="new websocket client" Feb 08 05:23:51 volumio volumio[30157]: info: Connection to go-librespot Websocket established Feb 08 05:23:51 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 08 05:23:51 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 08 05:23:51 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 08 05:23:51 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 05:23:51 volumio sudo[30619]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:51 volumio mpd_monitor.sh[30630]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 05:23:51 volumio sudo[30615]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:51 volumio sudo[30617]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:51 volumio volumio[30157]: info: Successfully started MPD Monitor Feb 08 05:23:51 volumio volumio[30157]: info: Successfully started MPD Monitor Feb 08 05:23:51 volumio volumio[30157]: info: Successfully started MPD Monitor Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=info msg="zeroconf server listening on port 42845" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="obtained new client token: AAA9J3ZcmXcMgHxofDKSxpi1VeIBY3F6dBPFMjDUDYUmQYqrUKtaGnMztPxys528ejoTMkrzS78PVkIb8P4bKsBE7Ln5OuFfvanmb56RYqvHpLvDhvTfaA5ukbPm8+92a0RzgkYChdt3bY8DzBaUcgG8u4NMRKyka5ES5I0nMNVoaypONUy1FDqJEzyuuoYlY6KlTvHCnxOxQ8KLS5Ocvox9Vw46ppa7zplG8r9dKP2+jlMne04Aa10=" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=debug msg="completed challenge" Feb 08 05:23:52 volumio go-librespot[30624]: time="2026-02-08T05:23:52-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:52 volumio volumio[30157]: info: Connection to go-librespot Websocket closed Feb 08 05:23:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Feb 08 05:23:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:55 volumio go-librespot[30649]: go-librespot daemon starting... Feb 08 05:23:55 volumio go-librespot[30650]: time="2026-02-08T05:23:55-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:55 volumio go-librespot[30650]: time="2026-02-08T05:23:55-06:00" level=debug msg="app state loaded" Feb 08 05:23:55 volumio go-librespot[30650]: time="2026-02-08T05:23:55-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:55 volumio go-librespot[30650]: time="2026-02-08T05:23:55-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:55 volumio volumio[30157]: info: Getting Spotify volume Feb 08 05:23:55 volumio volumio[30157]: info: Initializing connection to go-librespot Websocket Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=info msg="zeroconf server listening on port 36197" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="obtained new client token: AADldaYCs9DSB+utXDrTxYt2EHNqx2PAJbJSZl9esf2+rY2zMubh/99aWCz9Ur2rWlV6Ur6L5AyHlVVpqkLOqtsEpknK+nar0MkMvVWaD+jwXRMp/LaE2XnLnCu1ddTUq0g5hJBEtjs7lhSNrXZqcpRJwvQP7itMw1wuTK+KuYGP+3si8MxYBgQAWgipOoX+APHDEwWIubgWdkTUj5LZklmo1G6PQ6aVlAnZGsTOBgGkCXl6X3rYgicY8Q==" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:23:56 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="completed keyexchange" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=debug msg="completed challenge" Feb 08 05:23:56 volumio go-librespot[30650]: time="2026-02-08T05:23:56-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:23:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:23:58 volumio volumio[30157]: info: Error connecting to go-librespot Websocket: Error: socket hang up Feb 08 05:23:58 volumio volumio[30157]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 05:23:58 volumio volumio[30157]: Error: socket hang up Feb 08 05:23:58 volumio volumio[30157]: at connResetException (node:internal/errors:720:14) Feb 08 05:23:58 volumio volumio[30157]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 08 05:23:58 volumio volumio[30157]: at Socket.emit (node:events:526:35) Feb 08 05:23:58 volumio volumio[30157]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 08 05:23:58 volumio volumio[30157]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 08 05:23:58 volumio volumio[30157]: code: 'ECONNRESET', Feb 08 05:23:58 volumio volumio[30157]: response: undefined Feb 08 05:23:58 volumio volumio[30157]: } Feb 08 05:23:58 volumio volumio[30157]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 05:23:58 volumio sudo[30673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 05:22' Feb 08 05:23:58 volumio sudo[30673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:23:59 volumio sudo[30673]: pam_unix(sudo:session): session closed for user root Feb 08 05:23:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Feb 08 05:23:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:23:59 volumio go-librespot[30681]: go-librespot daemon starting... Feb 08 05:23:59 volumio go-librespot[30682]: time="2026-02-08T05:23:59-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:23:59 volumio go-librespot[30682]: time="2026-02-08T05:23:59-06:00" level=debug msg="app state loaded" Feb 08 05:23:59 volumio go-librespot[30682]: time="2026-02-08T05:23:59-06:00" level=debug msg="stored credentials not found" Feb 08 05:23:59 volumio go-librespot[30682]: time="2026-02-08T05:23:59-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:23:59 volumio volumio-remote-updater[751]: [2026-02-08 05:23:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 05:23:59 volumio volumio-remote-updater[751]: [2026-02-08 05:23:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 05:23:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:23:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 05:23:59 volumio systemd[1]: volumio.service: Consumed 50.241s CPU time. Feb 08 05:24:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 05:24:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=info msg="zeroconf server listening on port 35891" Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="obtained new client token: AACl7i7JHYCGjbXB3XucfhxW7A4fPm0tmLoozf27Rmge9BB9oKQ7g4QIPTnu0Az+HR2stCVG/2rgLvcRtBpJRNmj0ImvKnBxg1ygTrugT4PccmCGBp7scvoh4KZs2y6EeCShM8NrxNUr+KdB3IlqzwB57gQBn2+E4xHtlnAgdK3AJdynnkhXcaBid1Elbd4CmAxgYbgUq29b7v3TRETvb6ZqBnwlqES70STy7ZQgv6genigcnfNsWAU=" Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 723. Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=debug msg="completed challenge" Feb 08 05:24:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 05:24:00 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 05:24:00 volumio systemd[1]: volumio.service: Consumed 50.241s CPU time. Feb 08 05:24:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 05:24:00 volumio go-librespot[30682]: time="2026-02-08T05:24:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 05:24:00 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:24:02 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:02 volumio volumio[30696]: info: ----- Volumio3 ---- Feb 08 05:24:02 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:02 volumio volumio[30696]: info: ----- System startup ---- Feb 08 05:24:02 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:03 volumio volumio[30696]: info: MYVOLUMIO Environment detected Feb 08 05:24:03 volumio volumio[30696]: info: Plugin folders cleanup Feb 08 05:24:03 volumio volumio[30696]: info: Scanning into folder /volumio/app/plugins/ Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category audio_interface Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category miscellanea Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category music_service Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category plugins.json Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category system_controller Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category user_interface Feb 08 05:24:03 volumio volumio[30696]: info: Scanning into folder /data/plugins/ Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category audio_interface Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category music_service Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category system_controller Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category system_hardware Feb 08 05:24:03 volumio volumio[30696]: info: Scanning category user_interface Feb 08 05:24:03 volumio volumio[30696]: info: Plugin folders cleanup completed Feb 08 05:24:03 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:03 volumio volumio[30696]: info: ----- Core plugins startup ---- Feb 08 05:24:03 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:03 volumio volumio[30696]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 05:24:03 volumio volumio[30696]: info: Adding plugin upnp to MyMusic Plugins Feb 08 05:24:03 volumio volumio[30696]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 05:24:03 volumio volumio[30696]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 05:24:03 volumio volumio[30696]: info: Loading plugins from folder /data/plugins/ Feb 08 05:24:03 volumio volumio[30696]: info: Loading plugin "system"... Feb 08 05:24:03 volumio volumio[30696]: info: Loading plugin "appearance"... Feb 08 05:24:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Feb 08 05:24:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:03 volumio go-librespot[30724]: go-librespot daemon starting... Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=debug msg="app state loaded" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=info msg="zeroconf server listening on port 40893" Feb 08 05:24:03 volumio go-librespot[30725]: time="2026-02-08T05:24:03-06:00" level=debug msg="obtained new client token: AAAxdkIuTvAUkGe3GJ32ikUZnJHij8oTuS4ORa8odP0ddjhc/x0tbNOFo8jhXUfjK9t42ZVIs2YTwb+NCASGJhjtok2PDPBBn4/GUWJf6pL2keCiMepLdEhtjyyAyfMjPuMOalm0ugyXOAhYdXdwC9oOeNPFMF9O6BRCN6JPRitMRG9NsZuRMcGjjJ3s5sUQ6MKRnSo914Wh74ZA072Zm3MY6vqyi9ro7CXplFsq/8ThLl7p0tJHJuS7pQ==" Feb 08 05:24:04 volumio go-librespot[30725]: time="2026-02-08T05:24:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:04 volumio go-librespot[30725]: time="2026-02-08T05:24:04-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:04 volumio go-librespot[30725]: time="2026-02-08T05:24:04-06:00" level=debug msg="completed challenge" Feb 08 05:24:04 volumio go-librespot[30725]: time="2026-02-08T05:24:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "network"... Feb 08 05:24:04 volumio volumio[30696]: info: Refreshing Cached IP Addresses Feb 08 05:24:04 volumio sudo[30733]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 05:24:04 volumio sudo[30733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:04 volumio sudo[30733]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:04 volumio sudo[30735]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 05:24:04 volumio sudo[30735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "services"... Feb 08 05:24:04 volumio sudo[30735]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "alsa_controller"... Feb 08 05:24:04 volumio sudo[30743]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 05:24:04 volumio sudo[30743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:04 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "wizard"... Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "networkfs"... Feb 08 05:24:04 volumio volumio[30696]: info: Starting Udev Watcher for removable devices Feb 08 05:24:04 volumio volumio[30696]: info: Ignoring mount for partition: boot Feb 08 05:24:04 volumio volumio[30696]: info: Ignoring mount for partition: volumio Feb 08 05:24:04 volumio volumio[30696]: info: Ignoring mount for partition: volumio_data Feb 08 05:24:04 volumio volumio[30696]: info: Mounting Device Wikipedia Feb 08 05:24:04 volumio sudo[30772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 08 05:24:04 volumio sudo[30772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:04 volumio sudo[30772]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:04 volumio volumio[30696]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 05:24:04 volumio volumio[30696]: dmesg(1) may have more information after failed mount system call. Feb 08 05:24:04 volumio volumio[30696]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 08 05:24:04 volumio volumio[30696]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 05:24:04 volumio volumio[30696]: dmesg(1) may have more information after failed mount system call. Feb 08 05:24:04 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "volumio_command_line_client"... Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "upnp"... Feb 08 05:24:04 volumio volumio[30696]: info: [1770549844712] Starting Upmpd Daemon Feb 08 05:24:04 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "my_music"... Feb 08 05:24:04 volumio volumio[30696]: info: Loading plugin "mpd"... Feb 08 05:24:04 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:24:04 volumio volumio-remote-updater[751]: [2026-02-08 05:24:04] [connect] Successful connection Feb 08 05:24:04 volumio sudo[30743]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:05 volumio volumio[30696]: info: Loading plugin "upnp_browser"... Feb 08 05:24:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Feb 08 05:24:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:07 volumio go-librespot[30775]: go-librespot daemon starting... Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="app state loaded" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=info msg="zeroconf server listening on port 33777" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="obtained new client token: AAA47jc0nkiVATQXt8P3ZaSLzZRz5zmyd7DPKW3n6dm37tuB79BwihN/mZmm30sq9vMqCmKJjnw91Dw0jiEJ97u0HRL4FYOlm5rtrvYFQADkfNGb9TgGne71Rh5ZS/b+4uJZWRpoY5Gjdma4KkZ0BrlHwdTtsBQBFK92MK2VRST34x67nX1imk9nnNPaI8qRTP4dhfRnuMQIxSsXRKM2NOkDr3vhkWiZIaSzgVaZ1AqYPQ71xsaW24DRjw==" Feb 08 05:24:07 volumio go-librespot[30776]: time="2026-02-08T05:24:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "alarm-clock"... Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "airplay_emulation"... Feb 08 05:24:08 volumio volumio[30696]: info: Starting Shairport Sync Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "last_100"... Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "webradio"... Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "i2s_dacs"... Feb 08 05:24:08 volumio volumio[30696]: info: I2S DAC not set, start Auto-detection Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "volumiodiscovery"... Feb 08 05:24:08 volumio volumio[30696]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 05:24:08 volumio volumio[30696]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:24:08 volumio volumio[30696]: *** WARNING *** For more information see Feb 08 05:24:08 volumio volumio[30696]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 05:24:08 volumio volumio[30696]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:24:08 volumio volumio[30696]: *** WARNING *** For more information see Feb 08 05:24:08 volumio node[30696]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 05:24:08 volumio node[30696]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:24:08 volumio node[30696]: *** WARNING *** For more information see Feb 08 05:24:08 volumio node[30696]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 05:24:08 volumio node[30696]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 05:24:08 volumio node[30696]: *** WARNING *** For more information see Feb 08 05:24:08 volumio volumio[30696]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 05:24:08 volumio volumio[30696]: info: Discovery: Started advertising with name: Volumio Feb 08 05:24:08 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 05:24:08 volumio volumio[30696]: info: Loading plugin "bandcamp"... Feb 08 05:24:09 volumio go-librespot[30776]: time="2026-02-08T05:24:09-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:09 volumio go-librespot[30776]: time="2026-02-08T05:24:09-06:00" level=debug msg="completed challenge" Feb 08 05:24:09 volumio go-librespot[30776]: time="2026-02-08T05:24:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:09 volumio volumio[30696]: info: Plugin calmradio is not enabled Feb 08 05:24:09 volumio volumio[30696]: info: Loading plugin "soundcloud"... Feb 08 05:24:10 volumio volumio[30696]: info: Loading plugin "spop"... Feb 08 05:24:10 volumio volumio[30696]: info: Loading plugin "squeezelite_mc"... Feb 08 05:24:11 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:24:11 volumio volumio[30696]: info: Loading plugin "youtube2"... Feb 08 05:24:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Feb 08 05:24:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:12 volumio go-librespot[30789]: go-librespot daemon starting... Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="app state loaded" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:12 volumio volumio[30696]: info: Loading plugin "ytcr"... Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=info msg="zeroconf server listening on port 38855" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="obtained new client token: AAANxdA7uOz4cdsUH5NvhXvCsWTL6tGUxVC8/n8IfRAjXH0ZOnaD4KOtXEtPVEzN+/fvcMZoKXzkJ6D2QaMIVyk6/tEHzCVGcv3wkOmkmc153TTUBXLI8p+6enopD4W+m4HTQw+6eBvqd2BeRLyiz7ALS0i0TbdXlkJvp8K1clofXnNTMv1s18bMD/aXKzE6j8DP6vxHEOxRtumwTGloU116959shxcMLbcSwc77WtnoDw6yGLt83PHCiA==" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=debug msg="completed challenge" Feb 08 05:24:12 volumio go-librespot[30790]: time="2026-02-08T05:24:12-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:13 volumio volumio[30696]: info: Loading plugin "ytmusic"... Feb 08 05:24:14 volumio volumio[30696]: info: Plugin now_playing is not enabled Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "outputs"... Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "albumart"... Feb 08 05:24:14 volumio volumio[30696]: info: Plugin example_plugin is not enabled Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "inputs"... Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "updater_comm"... Feb 08 05:24:14 volumio volumio[30696]: info: Plugin mpdemulation is not enabled Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "rest_api"... Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "websocket"... Feb 08 05:24:14 volumio volumio[30696]: info: Starting Socket.io Server version 1.7.4 Feb 08 05:24:14 volumio volumio[30696]: info: Plugin fusiondsp is not enabled Feb 08 05:24:14 volumio volumio[30696]: info: Plugin mpdoutput is not enabled Feb 08 05:24:14 volumio volumio[30696]: info: Plugin RoonBridge is not enabled Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "podcast"... Feb 08 05:24:14 volumio volumio[30696]: info: ControllerPodcast::constructor Feb 08 05:24:14 volumio volumio[30696]: info: Loading plugin "volusonic"... Feb 08 05:24:14 volumio volumio[30797]: Forking 3 albumart workers Feb 08 05:24:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Feb 08 05:24:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:16 volumio go-librespot[30852]: go-librespot daemon starting... Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="app state loaded" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:16 volumio volumio[30821]: Starting albumart workers Feb 08 05:24:16 volumio volumio[30696]: info: Applying required configuration parameters for plugin volusonic Feb 08 05:24:16 volumio volumio[30696]: info: Loading plugin "backup_restore"... Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=info msg="zeroconf server listening on port 45711" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="obtained new client token: AAAxfmnyROk80bKD+2US/57O/F90KJANUwX5PxjL3usy5EjiozsmARQqDAlYza8p0Z/V9iRvJH+MugMqroFGOBDnXqZLBHYNAFhDGIFx9TW1R32cS8Hli/upMHBWUl9T5nixb8+sn8jXQSwp7S+th3PfxA5Ld9eKa/l9+PinqpXBjT2G0BBNhnKPmjNKk2dSqESfBwBmJaxqMacjb1ZAYOLZxezb43cq8vUsu7m20nnodFZb11i2y4ho0g==" Feb 08 05:24:16 volumio volumio[30822]: Starting albumart workers Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:16 volumio volumio[30696]: info: Applying required configuration parameters for plugin backup_restore Feb 08 05:24:16 volumio volumio[30696]: info: Plugin rpi_eeprom_config is not enabled Feb 08 05:24:16 volumio volumio[30696]: info: Plugin rpi_eeprom_updater is not enabled Feb 08 05:24:16 volumio volumio[30696]: info: Loading plugin "scheduledrestart"... Feb 08 05:24:16 volumio volumio[30823]: Starting albumart workers Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=debug msg="completed challenge" Feb 08 05:24:16 volumio go-librespot[30853]: time="2026-02-08T05:24:16-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:16 volumio volumio[30696]: info: Applying required configuration parameters for plugin scheduledrestart Feb 08 05:24:16 volumio volumio[30696]: info: Plugin Bluetoothremote is not enabled Feb 08 05:24:16 volumio volumio[30696]: info: Plugin music_services_shield is not enabled Feb 08 05:24:16 volumio volumio[30696]: info: Loading plugin "Systeminfo"... Feb 08 05:24:17 volumio volumio[30696]: info: Plugin peppymeterbasic is not enabled Feb 08 05:24:17 volumio volumio[30696]: info: Loading plugin "peppyspectrum"... Feb 08 05:24:17 volumio volumio-remote-updater[751]: [2026-02-08 05:24:17] [connect] Successful connection Feb 08 05:24:17 volumio volumio[30696]: info: Loading i18n strings for locale en Feb 08 05:24:17 volumio volumio[30696]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 05:24:17 volumio volumio[30696]: Updating browse sources language Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::initPlayerControls Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:24:17 volumio volumio[30696]: Express server listening on port 3000 Feb 08 05:24:17 volumio volumio[30696]: [Metrics] WebUI: 16s 121.79ms Feb 08 05:24:17 volumio volumio[30696]: info: CoreStateMachine::resetVolumioState Feb 08 05:24:17 volumio volumio[30696]: info: CoreStateMachine::getcurrentVolume Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:24:17 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:17 volumio sudo[30883]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 05:24:17 volumio sudo[30883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio sudo[30883]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:18 volumio sudo[30885]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 05:24:18 volumio sudo[30885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio sudo[30885]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:18 volumio volumio[30696]: info: Volumio Network Manager: Network status updated: 1 Feb 08 05:24:18 volumio volumio[30696]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::pushState Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioPushState Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::updateTrackBlock Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrackBlock Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:24:18 volumio volumio-remote-updater[751]: [2026-02-08 05:24:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770549857 101 Feb 08 05:24:18 volumio volumio[30696]: 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 08 05:24:18 volumio volumio[30696]: info: Reloading queue from file Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::setRepeat null single undefined Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::pushState Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioPushState Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::setRandom null Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::pushState Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioPushState Feb 08 05:24:18 volumio volumio[30696]: info: Setting Device type: Raspberry PI Feb 08 05:24:18 volumio volumio[30696]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:24:18 volumio volumio[30696]: info: CoreStateMachine::pushState Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioPushState Feb 08 05:24:18 volumio volumio[30696]: info: Completed loading Core Plugins Feb 08 05:24:18 volumio volumio[30696]: info: Preparing to generate the ALSA configuration file Feb 08 05:24:18 volumio sudo[30899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 05:24:18 volumio sudo[30899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio volumio[30696]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 05:24:18 volumio volumio[30696]: info: Discovery: Found device Volumio Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioGetState Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:18 volumio volumio[30696]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 05:24:18 volumio volumio[30696]: info: Discovery: Found device Volumio Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioGetState Feb 08 05:24:18 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:18 volumio volumio[30696]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 05:24:18 volumio volumio[30696]: info: Reading ALSA contributions from plugins. Feb 08 05:24:18 volumio sudo[30899]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:18 volumio volumio[30696]: info: Upmpdcli Daemon Started Feb 08 05:24:18 volumio volumio[30696]: info: Asound.conf file unchanged, so no further update is needed Feb 08 05:24:18 volumio volumio[30696]: info: Output device has changed, restarting MPD Feb 08 05:24:18 volumio volumio[30696]: info: Output device has changed, restarting Shairport Sync Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:18 volumio sudo[30902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:24:18 volumio sudo[30902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio sudo[30902]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:18 volumio sudo[30904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:24:18 volumio sudo[30904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio volumio[30696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:24:18 volumio volumio[30696]: info: ___________ START PLUGINS ___________ Feb 08 05:24:18 volumio volumio[30696]: info: ControllerMpd::onStart: Initializing MPD Feb 08 05:24:18 volumio volumio[30696]: info: Creating MPD Configuration file Feb 08 05:24:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:18 volumio volumio[30696]: info: [1770549858493] CoreMusicLibrary::Adding element Media Servers Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:18 volumio sudo[30912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:24:18 volumio sudo[30912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio sudo[30914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:24:18 volumio sudo[30912]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:18 volumio sudo[30914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:18 volumio volumio[30696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:18 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 05:24:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 05:24:18 volumio systemd[1]: mpd.service: Consumed 5.652s CPU time. Feb 08 05:24:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 05:24:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 05:24:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 05:24:18 volumio volumio[30696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:18 volumio volumio[30696]: info: [1770549858616] CoreMusicLibrary::Adding element Last_100 Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:18 volumio volumio[30696]: info: [1770549858620] CoreMusicLibrary::Adding element Webradio Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 05:24:18 volumio volumio[30696]: info: Initializing BBC Radios Feb 08 05:24:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 05:24:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:18 volumio volumio[30696]: info: [1770549858716] CoreMusicLibrary::Adding element Bandcamp Discover Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:18 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:18 volumio volumio[30696]: info: [1770549858727] CoreMusicLibrary::Adding element SoundCloud Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:18 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:18 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:18 volumio volumio[30696]: info: Creating Spotify config file Feb 08 05:24:18 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:18 volumio sudo[30934]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 05:24:18 volumio sudo[30934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 05:24:18 volumio sudo[30934]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Starting proxy server... Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:19 volumio volumio[30696]: info: [1770549859080] CoreMusicLibrary::Adding element YouTube2 Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:19 volumio volumio[30696]: info: [1770549859098] CoreMusicLibrary::Adding element YouTube Music Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube Music Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:19 volumio volumio[30696]: info: [1770549859110] CoreMusicLibrary::Adding element Podcast Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube Music Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Podcast Feb 08 05:24:19 volumio volumio[30696]: info: Loading i18n strings for locale en Feb 08 05:24:19 volumio volumio[30696]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 05:24:19 volumio volumio[30696]: Updating browse sources language Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube Music Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Podcast Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube Music Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Podcast Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:19 volumio volumio[30696]: info: [1770549859158] CoreMusicLibrary::Adding element Volusonic Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source YouTube Music Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Podcast Feb 08 05:24:19 volumio volumio[30696]: Cannot find translation for source Volusonic Feb 08 05:24:19 volumio volumio[30696]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 05:24:19 volumio volumio[30696]: info: Volumio Calling Home Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Proxy server started on port 40593 Feb 08 05:24:19 volumio volumio[30696]: info: Preparing to generate the ALSA configuration file Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 05:24:19 volumio volumio[30696]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 05:24:19 volumio volumio[30696]: info: Reading ALSA contributions from plugins. Feb 08 05:24:19 volumio volumio[30696]: info: MPD Permissions set Feb 08 05:24:19 volumio volumio[30696]: info: MPD Permissions set Feb 08 05:24:19 volumio volumio[30696]: info: Spotify config file written Feb 08 05:24:19 volumio sudo[30949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 05:24:19 volumio sudo[30949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:19 volumio volumio[30696]: 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 08 05:24:19 volumio volumio[30696]: info: VolumeController:: Volume=100 Mute =false Feb 08 05:24:19 volumio volumio[30696]: info: CoreStateMachine::pushState Feb 08 05:24:19 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::volumioPushState Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Server discovery started Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Player finder started Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 08 05:24:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:19 volumio volumio[30696]: info: No need to fix Spotify hosts Feb 08 05:24:19 volumio go-librespot[30952]: go-librespot daemon starting... Feb 08 05:24:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:19 volumio sudo[30949]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=debug msg="app state loaded" Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:19 volumio volumio[30696]: 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 08 05:24:19 volumio volumio[30696]: info: Volumio called home Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:19 volumio go-librespot[30964]: time="2026-02-08T05:24:19-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 08 05:24:19 volumio volumio[30696]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 08 05:24:20 volumio go-librespot[30964]: time="2026-02-08T05:24:20-06:00" level=info msg="zeroconf server listening on port 35845" Feb 08 05:24:20 volumio volumio[30696]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 05:24:20 volumio volumio[30696]: SPOTIFY: BQD42_C_2KonS3FLupLzYAPyC2UWvAxfv_641iub66z1q0O495l2haXl2kKajdn9S-Qpe2Dpbl1KMvKWU9QduuvG-M7WsC3b5Dq6Jwsx6ug2wrlp4J2AkZLPGdhDtg16KO7uOV51vl1isXFL4o8OZDNe_Q7TzyR3bdGsURT2JFbKmGPcrTv5CTe1JJlCUnywM6ikDdg6Sono-C8aCDqj03jr-3nT5AyiYxpeQWc Feb 08 05:24:20 volumio volumio[30696]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 05:24:20 volumio volumio[30696]: info: New Spotify access token = BQD42_C_2KonS3FLupLzYAPyC2UWvAxfv_641iub66z1q0O495l2haXl2kKajdn9S-Qpe2Dpbl1KMvKWU9QduuvG-M7WsC3b5Dq6Jwsx6ug2wrlp4J2AkZLPGdhDtg16KO7uOV51vl1isXFL4o8OZDNe_Q7TzyR3bdGsURT2JFbKmGPcrTv5CTe1JJlCUnywM6ikDdg6Sono-C8aCDqj03jr-3nT5AyiYxpeQWc Feb 08 05:24:20 volumio volumio[30696]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 05:24:20 volumio sudo[30973]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 08 05:24:20 volumio sudo[30973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio volumio[30696]: info: Starting Shairport Sync Feb 08 05:24:20 volumio sudo[30973]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio volumio[30696]: info: Starting Shairport Sync Feb 08 05:24:20 volumio volumio[30696]: info: Starting Shairport Sync Feb 08 05:24:20 volumio go-librespot[30964]: time="2026-02-08T05:24:20-06:00" level=debug msg="obtained new client token: AADUN6xvsaN3dWekyPmU+w+R1fUj03WoP0IuKvw93FfoKb/Nt3H/mROIqDj3R/NzmQbGICktSv3Ewry43XlSo5X2ZX4vbpHFQZhs5qd1KzVJg9Rse2vVpnO+Xq9UW9Q989YWY7EqYY4fLPxF2MdljibMxgZdb8WkLfYGoHczs/GpMrRWxE9fh2bdr/p4lWNtPN1V83M4UMzgcqA//95gKsuaqUKPOkJVasvKUSxUBCB7dWdM0C/min0=" Feb 08 05:24:20 volumio sudo[30978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:24:20 volumio sudo[30978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio sudo[30976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:24:20 volumio sudo[30976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio go-librespot[30964]: time="2026-02-08T05:24:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:20 volumio sudo[30980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:24:20 volumio sudo[30980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio go-librespot[30964]: time="2026-02-08T05:24:20-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:20 volumio volumio[30696]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 08 05:24:20 volumio go-librespot[30964]: time="2026-02-08T05:24:20-06:00" level=debug msg="completed challenge" Feb 08 05:24:20 volumio volumio[30696]: info: Asound.conf file unchanged, so no further update is needed Feb 08 05:24:20 volumio volumio[30696]: info: Output device has changed, restarting MPD Feb 08 05:24:20 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:24:20 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:24:20 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:24:20 volumio go-librespot[30964]: time="2026-02-08T05:24:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:20 volumio systemd[1]: shairport-sync.service: Consumed 2.014s CPU time. Feb 08 05:24:20 volumio volumio[30696]: info: Output device has changed, restarting Shairport Sync Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:20 volumio sudo[30990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 05:24:20 volumio sudo[30986]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 08 05:24:20 volumio sudo[30986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio sudo[30990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio volumio[30696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 05:24:20 volumio sudo[30988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 05:24:20 volumio sudo[30988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio sudo[30988]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio sudo[30986]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:24:20 volumio sudo[30978]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio sudo[30976]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio sudo[30980]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 05:24:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 05:24:20 volumio systemd[1]: mpd.service: Consumed 1.664s CPU time. Feb 08 05:24:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 05:24:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 05:24:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 05:24:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 05:24:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 05:24:20 volumio volumio[30696]: info: MPD Permissions set Feb 08 05:24:20 volumio volumio[30696]: info: Shairport-Sync Started Feb 08 05:24:20 volumio volumio[30696]: Error adding Membership: Error: addMembership EINVAL Feb 08 05:24:20 volumio volumio[30696]: info: Shairport-Sync Started Feb 08 05:24:20 volumio volumio[30696]: info: Shairport-Sync Started Feb 08 05:24:20 volumio volumio[30696]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 08 05:24:20 volumio sudo[31021]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 08 05:24:20 volumio sudo[31021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio sudo[31021]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:20 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::volumioGetState Feb 08 05:24:20 volumio volumio[30696]: info: CorePlayQueue::getTrack 0 Feb 08 05:24:20 volumio volumio[30696]: info: Starting Shairport Sync Feb 08 05:24:20 volumio sudo[31029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 08 05:24:20 volumio sudo[31029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio sudo[31032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 05:24:20 volumio sudo[31032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:20 volumio systemd[1]: Reloading. Feb 08 05:24:20 volumio sudo[31017]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 05:24:20 volumio sudo[31017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 05:24:20 volumio sudo[31017]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:20 volumio volumio[30696]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Feb 08 05:24:20 volumio volumio[30696]: info: Spotify Successfully logged in Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 05:24:20 volumio volumio[30696]: info: [1770549860909] CoreMusicLibrary::Adding element Spotify Feb 08 05:24:20 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source Bandcamp Discover Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source SoundCloud Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source YouTube2 Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source YouTube Music Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source Podcast Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source Volusonic Feb 08 05:24:20 volumio volumio[30696]: Cannot find translation for source Spotify Feb 08 05:24:21 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:21 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:21 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 05:24:21 volumio volumio[30696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 05:24:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 05:24:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 05:24:22 volumio sudo[31029]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:22 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:22 volumio sudo[31054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:22 volumio sudo[31054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:22 volumio sudo[31054]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 05:24:22 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 08 05:24:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 05:24:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:24:22 volumio systemd[1]: shairport-sync.service: Consumed 1.559s CPU time. Feb 08 05:24:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 05:24:22 volumio sudo[31032]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:22 volumio sudo[31058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 08 05:24:22 volumio sudo[31058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:22 volumio volumio[30696]: info: Shairport-Sync Started Feb 08 05:24:22 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 05:24:22 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 08 05:24:22 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 05:24:22 volumio sudo[31058]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:22 volumio volumio[30696]: info: go-librespot daemon successfully initialized Feb 08 05:24:23 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:24:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Feb 08 05:24:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:23 volumio sudo[31076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:23 volumio sudo[31076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:23 volumio go-librespot[31077]: go-librespot daemon starting... Feb 08 05:24:23 volumio go-librespot[31079]: time="2026-02-08T05:24:23-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:23 volumio go-librespot[31079]: time="2026-02-08T05:24:23-06:00" level=debug msg="app state loaded" Feb 08 05:24:23 volumio go-librespot[31079]: time="2026-02-08T05:24:23-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:23 volumio go-librespot[31079]: time="2026-02-08T05:24:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:23 volumio sudo[31076]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:23 volumio volumio[30696]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 08 05:24:23 volumio sudo[31087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 08 05:24:23 volumio sudo[31087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:23 volumio sudo[31087]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:23 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 08 05:24:23 volumio sudo[31090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 08 05:24:23 volumio sudo[31090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:24 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 05:24:24 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 05:24:24 volumio sudo[31090]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=info msg="zeroconf server listening on port 43733" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="obtained new client token: AADYKADNHadUOKrg+UD+B41dIFlaEaolPcsabL+JKplRUjpmeYG9rQ2Bq4p/PRSYY6izfKCYH1XugOBxbxh3v1HyEpz95trlCdeP9ShyMJLyjokbpmsi6BlxcyttkeGib8PeEx1RSGmhqcrnwZJI3OR9YJg8plTYOf64pL4QWiCt4THYoU11peqRBMXymrUqVHLv8nJ69w1joux66bAyj01W7W57w3tgPAm2K6QigOcsWpPXarSN4SdB8w==" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=debug msg="completed challenge" Feb 08 05:24:24 volumio go-librespot[31079]: time="2026-02-08T05:24:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:24 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:24 volumio sudo[31112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:24 volumio sudo[31112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:24 volumio sudo[31112]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:25 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:25 volumio sudo[31115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:25 volumio sudo[31115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:25 volumio sudo[31115]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:25 volumio volumio[30696]: info: Initializing connection to go-librespot Websocket Feb 08 05:24:26 volumio volumio[30696]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:24:26 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:26 volumio sudo[31118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:26 volumio sudo[31118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:26 volumio sudo[31118]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:26 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:26 volumio sudo[31121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:26 volumio sudo[31121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:26 volumio sudo[31121]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:27 volumio mpd[31052]: 2026-02-08T05:24:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 05:24:27 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 05:24:27 volumio sudo[30904]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:27 volumio sudo[30914]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:27 volumio sudo[30990]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:27 volumio volumio[30696]: error: MPD error: The expression evaluated to a falsy value: Feb 08 05:24:27 volumio volumio[30696]: assert.ok(self.idling) Feb 08 05:24:27 volumio volumio[30696]: error: The expression evaluated to a falsy value: Feb 08 05:24:27 volumio volumio[30696]: assert.ok(self.idling) Feb 08 05:24:27 volumio volumio[30696]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 05:24:27 volumio volumio[30696]: error: MPD error: The expression evaluated to a falsy value: Feb 08 05:24:27 volumio volumio[30696]: assert.ok(self.idling) Feb 08 05:24:27 volumio volumio[30696]: error: The expression evaluated to a falsy value: Feb 08 05:24:27 volumio volumio[30696]: assert.ok(self.idling) Feb 08 05:24:27 volumio volumio[30696]: info: MPD running with PID31052 Feb 08 05:24:27 volumio volumio[30696]: ,establishing connection Feb 08 05:24:27 volumio volumio[30696]: error: updateQueue error: null Feb 08 05:24:27 volumio volumio[30696]: error: updateQueue error: null Feb 08 05:24:27 volumio sudo[31128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 05:24:27 volumio sudo[31128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:27 volumio sudo[31128]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:27 volumio volumio[30696]: info: Completed starting Core Plugins Feb 08 05:24:27 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:27 volumio volumio[30696]: info: ----- MyVolumio plugins startup ---- Feb 08 05:24:27 volumio volumio[30696]: info: ------------------------------------------- Feb 08 05:24:27 volumio volumio[30696]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 05:24:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Feb 08 05:24:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:27 volumio go-librespot[31130]: go-librespot daemon starting... Feb 08 05:24:27 volumio go-librespot[31131]: time="2026-02-08T05:24:27-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:27 volumio go-librespot[31131]: time="2026-02-08T05:24:27-06:00" level=debug msg="app state loaded" Feb 08 05:24:27 volumio go-librespot[31131]: time="2026-02-08T05:24:27-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:27 volumio go-librespot[31131]: time="2026-02-08T05:24:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=info msg="zeroconf server listening on port 37889" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="obtained new client token: AACMKmj757kle0xk2q6FEPCiH4CCX8jsYWDYGMs2Nl6MCCJm3J7UG2kS7fNakk6CefKZqYkXi4ApsAebtfmH7mYTqeWEJf9O3rH+Tbr/1aX23sSlDCBEzUxmA3Yg3EK/3u7fS+H7xPtJcQLk5DDDsWpuoo6DiLZYN+dVt+rTMcC1n0J0syKL+efvHX9DZK1V9jRlj0U/kC79QsqSKfap5puJknISLBH8QYt4GeGbJKBIIBo2esQIskQ=" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=debug msg="completed challenge" Feb 08 05:24:28 volumio go-librespot[31131]: time="2026-02-08T05:24:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:29 volumio volumio[30696]: info: Initializing connection to go-librespot Websocket Feb 08 05:24:29 volumio volumio[30696]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:24:29 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 05:24:30 volumio sudo[31140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:24:30 volumio sudo[31140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:30 volumio sudo[31142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:24:30 volumio sudo[31142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:30 volumio sudo[31144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 05:24:30 volumio sudo[31144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 05:24:30 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 08 05:24:30 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 08 05:24:30 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 08 05:24:30 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 05:24:30 volumio sudo[31140]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:30 volumio sudo[31144]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:30 volumio sudo[31142]: pam_unix(sudo:session): session closed for user root Feb 08 05:24:30 volumio mpd_monitor.sh[31148]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 05:24:30 volumio volumio[30696]: info: Successfully started MPD Monitor Feb 08 05:24:30 volumio volumio[30696]: info: Successfully started MPD Monitor Feb 08 05:24:30 volumio volumio[30696]: info: Successfully started MPD Monitor Feb 08 05:24:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Feb 08 05:24:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:31 volumio go-librespot[31152]: go-librespot daemon starting... Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=debug msg="app state loaded" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=info msg="zeroconf server listening on port 36597" Feb 08 05:24:31 volumio go-librespot[31153]: time="2026-02-08T05:24:31-06:00" level=debug msg="obtained new client token: AAC7/NIin6szFXvIHR0s7turXQPUM/vB4HY2ucmh74r4wNabayWe+OfWR/KkO5j257tDwE7NPjkssBGM8HKPVgis4BR63E3KF8nZVKHD5MZkOVybONSghZjzykJCSbet+sF9W/RqGIhO+0eaIkQJIuIDwJhCUN3qm1SYBUmLSDtFcNhU2pMBogbBpeM2/mktT/Y9o+xqxGh9V1cCujq9luK6cO1/Wk96O+KFDVQZXgRz2i61h8XkT4mbEQ==" Feb 08 05:24:32 volumio go-librespot[31153]: time="2026-02-08T05:24:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:32 volumio volumio[30696]: info: Initializing connection to go-librespot Websocket Feb 08 05:24:32 volumio go-librespot[31153]: time="2026-02-08T05:24:32-06:00" level=debug msg="new websocket client" Feb 08 05:24:32 volumio volumio[30696]: info: Connection to go-librespot Websocket established Feb 08 05:24:32 volumio go-librespot[31153]: time="2026-02-08T05:24:32-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:32 volumio go-librespot[31153]: time="2026-02-08T05:24:32-06:00" level=debug msg="completed challenge" Feb 08 05:24:32 volumio go-librespot[31153]: time="2026-02-08T05:24:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:32 volumio volumio[30696]: info: Connection to go-librespot Websocket closed Feb 08 05:24:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:33 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 05:24:35 volumio volumio[30696]: info: Getting Spotify volume Feb 08 05:24:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Feb 08 05:24:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 05:24:35 volumio go-librespot[31175]: go-librespot daemon starting... Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=debug msg="app state loaded" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=debug msg="stored credentials not found" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=info msg="zeroconf server listening on port 43153" Feb 08 05:24:35 volumio go-librespot[31176]: time="2026-02-08T05:24:35-06:00" level=debug msg="obtained new client token: AAAbESONc5NNISHsA87MsBs2Ta8xzjE19xOoVcTr5WI0KZ3zjFRGf5upfRG5AuZnQMsHHlQTMUIFUpz8tLNqaCt1hrl1K5bidsW+SsJHNvRGIGFtSnzlTrlSJ8evNPB2E7yUQ3lJgvTZu4JK/1N0IxXeY+G1EhH9aeHzQhEBgAS8CLmW1pTwIyqI6WM7N3fymgPxiyWAxf12pVEUNqN77Ceu/JTnQzGDu97RvsNOHqF+1vwu2s4FdJm9lA==" Feb 08 05:24:36 volumio go-librespot[31176]: time="2026-02-08T05:24:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 05:24:36 volumio go-librespot[31176]: time="2026-02-08T05:24:36-06:00" level=debug msg="completed keyexchange" Feb 08 05:24:36 volumio go-librespot[31176]: time="2026-02-08T05:24:36-06:00" level=debug msg="completed challenge" Feb 08 05:24:36 volumio go-librespot[31176]: time="2026-02-08T05:24:36-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 05:24:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 05:24:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 05:24:37 volumio volumio[30696]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 05:24:37 volumio volumio[30696]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 05:24:37 volumio volumio[30696]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 08 05:24:37 volumio volumio[30696]: errno: -111, Feb 08 05:24:37 volumio volumio[30696]: code: 'ECONNREFUSED', Feb 08 05:24:37 volumio volumio[30696]: syscall: 'connect', Feb 08 05:24:37 volumio volumio[30696]: address: '127.0.0.1', Feb 08 05:24:37 volumio volumio[30696]: port: 9879, Feb 08 05:24:37 volumio volumio[30696]: response: undefined Feb 08 05:24:37 volumio volumio[30696]: } Feb 08 05:24:37 volumio volumio[30696]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 05:24:38 volumio sudo[31198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 05:23' Feb 08 05:24:38 volumio sudo[31198]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"