Aug 28 21:35:02 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:02 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:02 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:02 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:02 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:08 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:08 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:08 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:08 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:08 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:13 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:13 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:13 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:13 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:13 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:18 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:18 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:18 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:18 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:18 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 28 21:35:19 volumio volumio[1158]: info: Preparing to save Alsa Options, stopping services first Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:19 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::volumioPause Aug 28 21:35:19 volumio volumio[1158]: info: CoreStateMachine::pause Aug 28 21:35:19 volumio volumio[1158]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"},"output_device":{"value":1,"label":"HiFiBerry DAC"},"disallowPush":true} Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:19 volumio volumio[1158]: info: Enabling I2S DAC: HiFiBerry DAC Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:19 volumio sudo[3645]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 28 21:35:19 volumio sudo[3645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:19 volumio sudo[3645]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:19 volumio volumio[1158]: info: No Overlays Loaded Aug 28 21:35:19 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio sudo[3649]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Aug 28 21:35:20 volumio sudo[3649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:20 volumio volumio[1158]: warn: Unable to locate the device HiFiBerry DAC a reboot may be required. Aug 28 21:35:20 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Aug 28 21:35:20 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Aug 28 21:35:20 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Aug 28 21:35:20 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Aug 28 21:35:20 volumio sudo[3649]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: Device 2 does not have any Mixer Control Available Aug 28 21:35:20 volumio (udev-worker)[3666]: controlC3: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 3' failed with exit code 99. Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 21:35:20 volumio volumio[1158]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 28 21:35:20 volumio volumio[1158]: info: Disabling external Volume Control Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: Preparing to generate the ALSA configuration file Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:20 volumio volumio[1158]: info: Retrieving Cloud Streaming UI Aug 28 21:35:20 volumio volumio[1158]: info: Aug 28 21:35:20 volumio volumio[1158]: ---------------------------- MPD announces state update: mixer Aug 28 21:35:20 volumio volumio[1158]: info: ControllerMpd::getState Aug 28 21:35:20 volumio volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 28 21:35:20 volumio volumio[1158]: info: Aug 28 21:35:20 volumio volumio[1158]: ---------------------------- MPD announces state update: mixer Aug 28 21:35:20 volumio volumio[1158]: info: ControllerMpd::getState Aug 28 21:35:20 volumio volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 28 21:35:20 volumio volumio[1158]: info: Aug 28 21:35:20 volumio volumio[1158]: ---------------------------- MPD announces state update: mixer Aug 28 21:35:20 volumio volumio[1158]: info: ControllerMpd::getState Aug 28 21:35:20 volumio volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 28 21:35:20 volumio volumio[1158]: info: sendMpdCommand status took 6 milliseconds Aug 28 21:35:20 volumio volumio[1158]: info: sendMpdCommand status took 5 milliseconds Aug 28 21:35:20 volumio volumio[1158]: verbose: ControllerMpd::parseState Aug 28 21:35:20 volumio volumio[1158]: verbose: ControllerMpd::parseState Aug 28 21:35:20 volumio volumio[1158]: info: ControllerMpd::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::servicePushState Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 21:35:20 volumio volumio[1158]: verbose: CURRENT POSITION 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: No code Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: ControllerMpd::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::servicePushState Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 21:35:20 volumio volumio[1158]: verbose: CURRENT POSITION 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: No code Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: ------------------------------ 90ms Aug 28 21:35:20 volumio volumio[1158]: info: ------------------------------ 88ms Aug 28 21:35:20 volumio volumio[1158]: info: I2S Param [object Object] successfully enabled Aug 28 21:35:20 volumio volumio[1158]: info: sendMpdCommand status took 114 milliseconds Aug 28 21:35:20 volumio volumio[1158]: verbose: ControllerMpd::parseState Aug 28 21:35:20 volumio volumio[1158]: info: ControllerMpd::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::servicePushState Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 21:35:20 volumio volumio[1158]: verbose: CURRENT POSITION 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: No code Aug 28 21:35:20 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:20 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:20 volumio volumio[1158]: info: ------------------------------ 147ms Aug 28 21:35:20 volumio volumio[1158]: info: Getting Tidal Cloud Configuration Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:20 volumio volumio[1158]: info: Getting Qobuz Cloud Configuration Aug 28 21:35:20 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:20 volumio volumio[1158]: info: Getting Spotify Cloud Configuration Aug 28 21:35:20 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:20 volumio volumio[1158]: info: Saving Spotify Acccount Aug 28 21:35:20 volumio volumio[1158]: info: Got Tidal Cloud Configuration Aug 28 21:35:20 volumio volumio[1158]: info: Got it Aug 28 21:35:20 volumio volumio[1158]: info: Got it Aug 28 21:35:20 volumio volumio[1158]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:20 volumio volumio[1158]: info: Getting Alsa Cards List without I2S DAC Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 21:35:20 volumio volumio[1158]: info: Asound.conf file written Aug 28 21:35:20 volumio sudo[3697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 28 21:35:20 volumio sudo[3697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:20 volumio sudo[3697]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: No state is present for card b1 Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: /usr/share/alsa/init/bcm2835:14: control element not found Aug 28 21:35:20 volumio volumio[1158]: No state is present for card b1 Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: No state is present for card Headphones Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: /usr/share/alsa/init/bcm2835:20: control element not found Aug 28 21:35:20 volumio volumio[1158]: No state is present for card Headphones Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: No state is present for card vc4hdmi Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 28 21:35:20 volumio volumio[1158]: Hardware is initialized using a generic method Aug 28 21:35:20 volumio volumio[1158]: No state is present for card vc4hdmi Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: No state is present for card sndrpihifiberry Aug 28 21:35:20 volumio volumio[1158]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 28 21:35:20 volumio volumio[1158]: Found hardware: "RPi-simple" "" "" "" "" Aug 28 21:35:20 volumio volumio[1158]: Hardware is initialized using a generic method Aug 28 21:35:20 volumio volumio[1158]: No state is present for card sndrpihifiberry Aug 28 21:35:20 volumio volumio[1158]: info: Output device has changed, restarting MPD Aug 28 21:35:20 volumio volumio[1158]: info: Output device has changed, restarting Shairport Sync Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio sudo[3703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 21:35:20 volumio sudo[3703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:20 volumio sudo[3703]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:20 volumio sudo[3706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 21:35:20 volumio sudo[3706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:20 volumio volumio[1158]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 21:35:20 volumio volumio[1158]: info: Output device has changed, restarting MPD Aug 28 21:35:20 volumio volumio[1158]: info: Output device has changed, restarting Shairport Sync Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 28 21:35:20 volumio sudo[3713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio sudo[3713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:20 volumio sudo[3713]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:20 volumio sudo[3715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 21:35:20 volumio sudo[3715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:20 volumio volumio[1158]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:20 volumio volumio[1158]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 28 21:35:20 volumio volumio[1158]: info: MPD Permissions set Aug 28 21:35:20 volumio volumio[1158]: info: MPD Permissions set Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:20 volumio volumio[1158]: info: Starting Shairport Sync Aug 28 21:35:20 volumio volumio[1158]: info: Starting Shairport Sync Aug 28 21:35:21 volumio sudo[3734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 21:35:21 volumio sudo[3734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:21 volumio sudo[3736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 21:35:21 volumio sudo[3736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 21:35:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 21:35:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 21:35:21 volumio systemd[1]: shairport-sync.service: Consumed 3.903s CPU time. Aug 28 21:35:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 21:35:21 volumio sudo[3734]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:21 volumio volumio[1158]: info: Shairport-Sync Started Aug 28 21:35:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 21:35:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 21:35:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 21:35:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 21:35:21 volumio sudo[3736]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:21 volumio volumio[1158]: info: Shairport-Sync Started Aug 28 21:35:21 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 28 21:35:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 21:35:21 volumio systemd[1]: mpd.service: Consumed 6.572s CPU time. Aug 28 21:35:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 28 21:35:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 21:35:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 21:35:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 21:35:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 21:35:21 volumio sudo[3757]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 21:35:21 volumio sudo[3757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 21:35:21 volumio sudo[3757]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:23 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:23 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:23 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:23 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:23 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:24 volumio volumio[1158]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 28 21:35:24 volumio volumio[1158]: info: CoreStateMachine::getcurrentVolume Aug 28 21:35:24 volumio volumio[1158]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 21:35:24 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:24 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:24 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:24 volumio mpd[3759]: 2026-08-28T21:35:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 21:35:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 28 21:35:24 volumio sudo[3715]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:24 volumio sudo[3706]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:24 volumio volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Aug 28 21:35:24 volumio volumio[1158]: assert.ok(self.idling) Aug 28 21:35:24 volumio volumio[1158]: error: The expression evaluated to a falsy value: Aug 28 21:35:24 volumio volumio[1158]: assert.ok(self.idling) Aug 28 21:35:24 volumio volumio[1158]: error: updateQueue error: null Aug 28 21:35:28 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:28 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:28 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:28 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:28 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:29 volumio sudo[3783]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 21:35:29 volumio sudo[3783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:29 volumio sudo[3783]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:29 volumio sudo[3785]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 21:35:29 volumio sudo[3785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:29 volumio sudo[3785]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:29 volumio volumio[1158]: verbose: New Socket.io Connection to 192.168.0.31 from 192.168.0.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 YaBrowser/26.8.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 28 21:35:29 volumio volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 21:35:29 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 21:35:29 volumio volumio[1158]: info: Received Get System Info Aug 28 21:35:29 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:35:29 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:35:29 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:35:29 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:29 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:29 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:35:34 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:34 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:34 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:34 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:34 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:34 volumio sudo[3793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 21:35:34 volumio sudo[3793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:34 volumio sudo[3793]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:34 volumio sudo[3795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 21:35:34 volumio sudo[3795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:34 volumio sudo[3795]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:34 volumio volumio[1158]: verbose: New Socket.io Connection to 192.168.0.31 from 192.168.0.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 YaBrowser/26.8.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 28 21:35:34 volumio volumio[1158]: info: Received Get System Info Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:35:34 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:34 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:35:34 volumio volumio-remote-updater[743]: Test mode disabled Aug 28 21:35:34 volumio volumio-remote-updater[743]: Alpha mode disabled Aug 28 21:35:34 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:34 volumio volumio[1158]: info: Retrieving Cloud Streaming UI Aug 28 21:35:34 volumio volumio[1158]: info: Getting Tidal Cloud Configuration Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:34 volumio volumio[1158]: info: Getting Qobuz Cloud Configuration Aug 28 21:35:34 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:34 volumio volumio[1158]: info: Getting Spotify Cloud Configuration Aug 28 21:35:34 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:34 volumio volumio[1158]: info: Saving Spotify Acccount Aug 28 21:35:34 volumio volumio[1158]: info: Got Tidal Cloud Configuration Aug 28 21:35:34 volumio volumio[1158]: info: Got it Aug 28 21:35:34 volumio volumio[1158]: info: Got it Aug 28 21:35:34 volumio volumio[1158]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:34 volumio volumio[1158]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 28 21:35:34 volumio volumio[1158]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 21:35:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 21:35:35 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 28 21:35:35 volumio volumio[1158]: info: Loading i18n strings for locale ru Aug 28 21:35:35 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 28 21:35:35 volumio volumio[1158]: info: Setting timezone to Europe/Volgograd Aug 28 21:35:35 volumio sudo[3816]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 28 21:35:35 volumio sudo[3816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:35 volumio sudo[3816]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:35 volumio sudo[3820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Volgograd /etc/localtime Aug 28 21:35:35 volumio sudo[3820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:35 volumio sudo[3820]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:35 volumio sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 28 21:35:35 volumio sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:35 volumio sudo[3824]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:35 volumio sudo[3828]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Volgograd Aug 28 21:35:35 volumio sudo[3828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:35 volumio dbus-daemon[724]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.334' (uid=0 pid=3829 comm="/usr/bin/timedatectl set-timezone Europe/Volgograd") Aug 28 21:35:36 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 28 21:35:36 volumio dbus-daemon[724]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 28 21:35:36 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 28 21:35:36 volumio sudo[3828]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:36 volumio volumio[1158]: info: Fetching Streaming Services browse cache Aug 28 21:35:36 volumio volumio[1158]: Updating browse sources language Aug 28 21:35:36 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 21:35:39 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:39 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:39 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:39 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:39 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:41 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Aug 28 21:35:41 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Aug 28 21:35:41 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 21:35:41 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:41 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 28 21:35:43 volumio volumio[1158]: info: Preparing to save Alsa Options, stopping services first Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:43 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::volumioPause Aug 28 21:35:43 volumio volumio[1158]: info: CoreStateMachine::pause Aug 28 21:35:43 volumio volumio[1158]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"HiFiBerry DAC"},"output_device":{"value":1,"label":"HiFiBerry DAC"},"disallowPush":true} Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: Device 3 does not have any Mixer Control Available Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 21:35:43 volumio volumio[1158]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 28 21:35:43 volumio volumio[1158]: info: Disabling external Volume Control Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:43 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:43 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:43 volumio volumio[1158]: info: Preparing to generate the ALSA configuration file Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:43 volumio volumio[1158]: info: Retrieving Cloud Streaming UI Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 21:35:43 volumio volumio[1158]: info: Getting Alsa Cards List without I2S DAC Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 21:35:43 volumio volumio[1158]: info: Asound.conf file unchanged, so no further update is needed Aug 28 21:35:43 volumio volumio[1158]: info: Output device has changed, restarting MPD Aug 28 21:35:43 volumio volumio[1158]: info: Output device has changed, restarting Shairport Sync Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio sudo[3881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 21:35:43 volumio sudo[3881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:43 volumio sudo[3881]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:43 volumio volumio[1158]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 21:35:43 volumio sudo[3885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 21:35:43 volumio sudo[3885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:43 volumio volumio[1158]: info: Getting Tidal Cloud Configuration Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:43 volumio volumio[1158]: info: Getting Qobuz Cloud Configuration Aug 28 21:35:43 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:43 volumio volumio[1158]: info: Getting Spotify Cloud Configuration Aug 28 21:35:43 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:43 volumio volumio[1158]: info: Saving Spotify Acccount Aug 28 21:35:43 volumio volumio[1158]: info: Got Tidal Cloud Configuration Aug 28 21:35:43 volumio volumio[1158]: info: Got it Aug 28 21:35:43 volumio volumio[1158]: info: Got it Aug 28 21:35:43 volumio volumio[1158]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 21:35:43 volumio volumio[1158]: info: MPD Permissions set Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:43 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:35:43 volumio volumio[1158]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 28 21:35:43 volumio volumio[1158]: info: Starting Shairport Sync Aug 28 21:35:43 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 28 21:35:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 21:35:43 volumio systemd[1]: mpd.service: Consumed 3.130s CPU time. Aug 28 21:35:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 28 21:35:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 21:35:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 21:35:43 volumio sudo[3897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 21:35:43 volumio sudo[3897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 21:35:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 21:35:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 21:35:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 21:35:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 21:35:43 volumio systemd[1]: shairport-sync.service: Consumed 1.700s CPU time. Aug 28 21:35:43 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 21:35:43 volumio sudo[3897]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:43 volumio volumio[1158]: info: Shairport-Sync Started Aug 28 21:35:43 volumio sudo[3900]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 21:35:43 volumio sudo[3900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 21:35:43 volumio sudo[3900]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:44 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:44 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:44 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:44 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:44 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:46 volumio mpd[3917]: 2026-08-28T21:35:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 21:35:46 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 28 21:35:46 volumio sudo[3885]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:46 volumio volumio[1158]: error: updateQueue error: null Aug 28 21:35:46 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 28 21:35:47 volumio volumio[1158]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 28 21:35:47 volumio volumio[1158]: info: CoreStateMachine::getcurrentVolume Aug 28 21:35:47 volumio volumio[1158]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 21:35:47 volumio volumio[1158]: info: CoreStateMachine::pushState Aug 28 21:35:47 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:47 volumio volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 28 21:35:47 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Aug 28 21:35:47 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 28 21:35:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Aug 28 21:35:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Aug 28 21:35:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 28 21:35:49 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:49 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:49 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:49 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:49 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:50 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 28 21:35:50 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 28 21:35:50 volumio systemd[1]: setdatetime-helper.service: Consumed 1.055s CPU time. Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::Reload Ui Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Aug 28 21:35:53 volumio volumio[1158]: info: Wizard terminated Successfully Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::volumiosetStartupVolume Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 21:35:53 volumio volumio[1158]: info: Player Reboot required after I2S DAC has been enabled in wizard Aug 28 21:35:53 volumio volumio[1158]: info: Received Get System Info Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:35:53 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:53 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 21:35:53 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 21:35:54 volumio sudo[3989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 21:35:54 volumio sudo[3989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:54 volumio sudo[3989]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:54 volumio sudo[3991]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 21:35:54 volumio sudo[3991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:54 volumio sudo[3991]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:54 volumio volumio[1158]: verbose: New Socket.io Connection to 192.168.0.31 from 192.168.0.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 YaBrowser/26.8.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 21:35:54 volumio volumio5-onboarding[1936]: time=2026-08-28T18:35:54.418Z level=INFO msg="service successfully established" component=discovery/localnet Aug 28 21:35:54 volumio sudo[3995]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 21:35:54 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:54 volumio sudo[3995]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:54 volumio sudo[3997]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 21:35:54 volumio sudo[3997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 21:35:54 volumio sudo[3997]: pam_unix(sudo:session): session closed for user root Aug 28 21:35:54 volumio volumio[1158]: verbose: New Socket.io Connection to 192.168.0.31 from 192.168.0.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 YaBrowser/26.8.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:54 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 21:35:54 volumio volumio[1158]: info: Received Get System Info Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:35:54 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:54 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:54 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:54 volumio volumio[1158]: info: Listing playlists Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 21:35:54 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 21:35:54 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:54 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:54 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:35:54 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:35:54 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:35:55 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:55 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:56 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 28 21:35:56 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 21:35:56 volumio volumio[1158]: info: Received Get System Info Aug 28 21:35:56 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:35:56 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:35:56 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:35:56 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:56 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:56 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:35:57 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 21:35:57 volumio volumio[1158]: info: Received Get System Info Aug 28 21:35:57 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:35:57 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:35:57 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:35:57 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:35:57 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:35:57 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:36:00 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:00 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:00 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:00 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:00 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:36:02 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Aug 28 21:36:04 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 28 21:36:05 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:05 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:05 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:05 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:05 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:36:06 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 28 21:36:06 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 21:36:06 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 28 21:36:06 volumio volumio-remote-updater[743]: Test mode disabled Aug 28 21:36:06 volumio volumio-remote-updater[743]: Alpha mode disabled Aug 28 21:36:06 volumio volumio-remote-updater[743]: Alpha legacy test mode disabled Aug 28 21:36:06 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 28 21:36:06 volumio volumio[1158]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 21:36:06 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 21:36:08 volumio volumio[1158]: info: CoreCommandRouter::Close All Modals sent Aug 28 21:36:08 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Aug 28 21:36:10 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:10 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:10 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:10 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:10 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:36:10 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:36:10 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:36:10 volumio volumio[1158]: info: Retrieving Cloud Streaming UI Aug 28 21:36:10 volumio volumio[1158]: info: Getting Tidal Cloud Configuration Aug 28 21:36:10 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:36:10 volumio volumio[1158]: info: Getting Qobuz Cloud Configuration Aug 28 21:36:10 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:36:10 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:36:10 volumio volumio[1158]: info: Getting Spotify Cloud Configuration Aug 28 21:36:10 volumio volumio[1158]: info: Asking plugin for UI Config Aug 28 21:36:10 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:36:10 volumio volumio[1158]: info: Saving Spotify Acccount Aug 28 21:36:10 volumio volumio[1158]: info: Got Tidal Cloud Configuration Aug 28 21:36:10 volumio volumio[1158]: info: Got it Aug 28 21:36:10 volumio volumio[1158]: info: Got it Aug 28 21:36:10 volumio volumio[1158]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 21:36:10 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 21:36:10 volumio volumio[1158]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 21:36:11 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 21:36:12 volumio volumio[1158]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 21:36:12 volumio volumio[1158]: info: Received Get System Version Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 21:36:12 volumio volumio[1158]: info: Received Get System Info Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 21:36:12 volumio volumio[1158]: info: Discovery: Getting this device information Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 28 21:36:12 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 28 21:36:12 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 21:36:15 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 21:36:15 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:15 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:15 volumio volumio[1158]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 28 21:36:15 volumio volumio[1158]: info: Discovery: Restarting Browsing Aug 28 21:36:15 volumio volumio[1158]: info: Discovery: Restart already pending, ignoring duplicate call Aug 28 21:36:16 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 28 21:36:16 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 28 21:36:16 volumio volumio[1158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 21:36:16 volumio volumio[1158]: Error: getaddrinfo -3007 Aug 28 21:36:16 volumio volumio[1158]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 28 21:36:16 volumio volumio[1158]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 28 21:36:16 volumio volumio[1158]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 28 21:36:16 volumio volumio[1158]: code: -3007, Aug 28 21:36:16 volumio volumio[1158]: errno: -3007, Aug 28 21:36:16 volumio volumio[1158]: syscall: 'getaddrinfo' Aug 28 21:36:16 volumio volumio[1158]: } Aug 28 21:36:16 volumio volumio[1158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 21:36:17 volumio sudo[4097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 21:35' Aug 28 21:36:17 volumio sudo[4097]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"