May 23 10:23:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 23 10:23:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 23 10:23:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 10:23:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:02 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 10:23:15 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 23 10:23:15 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 10:23:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 23 10:23:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 10:23:21 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 10:23:29 volumio volumio[1058]: info: Preparing to save Alsa Options, stopping services first May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPause May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pause May 23 10:23:29 volumio volumio[1058]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"},"output_device":{"value":1,"label":"HiFiBerry Digi+ Pro"},"disallowPush":true} May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: Enabling I2S DAC: HiFiBerry Digi+ Pro May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio sudo[3816]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 23 10:23:29 volumio sudo[3816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3816]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio volumio[1058]: info: No Overlays Loaded May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio sudo[3819]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-digi-pro May 23 10:23:29 volumio sudo[3819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3819]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/#sound-dai-cells May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/compatible May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/reg May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/PVDD-supply May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/DVDD-supply May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/status May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio May 23 10:23:29 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: Device 2 does not have any Mixer Control Available May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 10:23:29 volumio volumio[1058]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 10:23:29 volumio volumio[1058]: info: Disabling external Volume Control May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: Preparing to generate the ALSA configuration file May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 23 10:23:29 volumio volumio[1058]: info: May 23 10:23:29 volumio volumio[1058]: ---------------------------- MPD announces state update: mixer May 23 10:23:29 volumio volumio[1058]: info: ControllerMpd::getState May 23 10:23:29 volumio sudo[3846]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 23 10:23:29 volumio sudo[3846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status May 23 10:23:29 volumio volumio[1058]: info: May 23 10:23:29 volumio volumio[1058]: ---------------------------- MPD announces state update: mixer May 23 10:23:29 volumio volumio[1058]: info: ControllerMpd::getState May 23 10:23:29 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status May 23 10:23:29 volumio volumio[1058]: info: May 23 10:23:29 volumio volumio[1058]: ---------------------------- MPD announces state update: mixer May 23 10:23:29 volumio volumio[1058]: info: ControllerMpd::getState May 23 10:23:29 volumio volumio[1058]: verbose: ControllerMpd::sendMpdCommand status May 23 10:23:29 volumio volumio[1058]: info: sendMpdCommand status took 6 milliseconds May 23 10:23:29 volumio volumio[1058]: info: sendMpdCommand status took 5 milliseconds May 23 10:23:29 volumio volumio[1058]: verbose: ControllerMpd::parseState May 23 10:23:29 volumio volumio[1058]: verbose: ControllerMpd::parseState May 23 10:23:29 volumio volumio[1058]: info: ControllerMpd::pushState May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::servicePushState May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio sudo[3848]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 23 10:23:29 volumio sudo[3848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: 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} May 23 10:23:29 volumio volumio[1058]: verbose: CURRENT POSITION 0 May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio sudo[3848]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: No code May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: ControllerMpd::pushState May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::servicePushState May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: 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} May 23 10:23:29 volumio volumio[1058]: verbose: CURRENT POSITION 0 May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: No code May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: ------------------------------ 46ms May 23 10:23:29 volumio volumio[1058]: info: ------------------------------ 43ms May 23 10:23:29 volumio volumio[1058]: info: I2S Param [object Object] successfully enabled May 23 10:23:29 volumio volumio[1058]: info: sendMpdCommand status took 47 milliseconds May 23 10:23:29 volumio volumio[1058]: verbose: ControllerMpd::parseState May 23 10:23:29 volumio volumio[1058]: info: ControllerMpd::pushState May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::servicePushState May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: 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} May 23 10:23:29 volumio volumio[1058]: verbose: CURRENT POSITION 0 May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::syncState stateService stop May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::syncState currentStatus stop May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: No code May 23 10:23:29 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:29 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:29 volumio volumio[1058]: info: ------------------------------ 66ms May 23 10:23:29 volumio volumio[1058]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 23 10:23:29 volumio volumio[1058]: wlan0 Interface doesn't support scanning : Device or resource busy May 23 10:23:29 volumio volumio[1058]: info: Cannot use regular scanning, forcing with ap-force May 23 10:23:29 volumio sudo[3854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 23 10:23:29 volumio sudo[3854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3854]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio volumio[1058]: command failed: Device or resource busy (-16) May 23 10:23:29 volumio volumio[1058]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 23 10:23:29 volumio volumio[1058]: command failed: Device or resource busy (-16) May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 10:23:29 volumio volumio[1058]: info: Getting Alsa Cards List without I2S DAC May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 10:23:29 volumio volumio[1058]: info: Asound.conf file written May 23 10:23:29 volumio sudo[3872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 23 10:23:29 volumio sudo[3872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3872]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio volumio[1058]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 23 10:23:29 volumio volumio[1058]: No state is present for card b1 May 23 10:23:29 volumio volumio[1058]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 23 10:23:29 volumio volumio[1058]: /usr/share/alsa/init/bcm2835:14: control element not found May 23 10:23:29 volumio volumio[1058]: No state is present for card b1 May 23 10:23:29 volumio volumio[1058]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 23 10:23:29 volumio volumio[1058]: No state is present for card Headphones May 23 10:23:29 volumio volumio[1058]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 23 10:23:29 volumio volumio[1058]: /usr/share/alsa/init/bcm2835:20: control element not found May 23 10:23:29 volumio volumio[1058]: No state is present for card Headphones May 23 10:23:29 volumio volumio[1058]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 23 10:23:29 volumio volumio[1058]: No state is present for card sndrpihifiberry May 23 10:23:29 volumio volumio[1058]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 23 10:23:29 volumio volumio[1058]: Found hardware: "RPi-WM8804" "" "" "" "" May 23 10:23:29 volumio volumio[1058]: Hardware is initialized using a generic method May 23 10:23:29 volumio volumio[1058]: No state is present for card sndrpihifiberry May 23 10:23:29 volumio volumio[1058]: info: Output device has changed, restarting MPD May 23 10:23:29 volumio volumio[1058]: info: Output device has changed, restarting Shairport Sync May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio sudo[3878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 10:23:29 volumio sudo[3878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3878]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio sudo[3880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 10:23:29 volumio sudo[3880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 10:23:29 volumio volumio[1058]: info: Output device has changed, restarting MPD May 23 10:23:29 volumio volumio[1058]: info: Output device has changed, restarting Shairport Sync May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio sudo[3888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 10:23:29 volumio sudo[3888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3888]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 23 10:23:29 volumio sudo[3892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 10:23:29 volumio sudo[3892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 10:23:29 volumio volumio[1058]: info: MPD Permissions set May 23 10:23:29 volumio volumio[1058]: info: MPD Permissions set May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:23:29 volumio volumio[1058]: info: Starting Shairport Sync May 23 10:23:29 volumio volumio[1058]: info: Starting Shairport Sync May 23 10:23:29 volumio sudo[3909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 10:23:29 volumio sudo[3909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio sudo[3911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 10:23:29 volumio sudo[3911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 23 10:23:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 23 10:23:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 23 10:23:29 volumio systemd[1]: shairport-sync.service: Consumed 4.610s CPU time. May 23 10:23:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 23 10:23:29 volumio sudo[3909]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio sudo[3911]: pam_unix(sudo:session): session closed for user root May 23 10:23:29 volumio volumio[1058]: info: Shairport-Sync Started May 23 10:23:29 volumio volumio[1058]: info: Shairport-Sync Started May 23 10:23:29 volumio systemd[1]: mpd.service: Deactivated successfully. May 23 10:23:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 23 10:23:29 volumio systemd[1]: mpd.service: Consumed 3.309s CPU time. May 23 10:23:29 volumio systemd[1]: mpd.socket: Deactivated successfully. May 23 10:23:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 23 10:23:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 23 10:23:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 23 10:23:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 23 10:23:30 volumio sudo[3929]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 10:23:30 volumio sudo[3929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 10:23:30 volumio sudo[3929]: pam_unix(sudo:session): session closed for user root May 23 10:23:31 volumio mpd[3932]: 2025-05-23T10:23:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 10:23:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 23 10:23:31 volumio sudo[3892]: pam_unix(sudo:session): session closed for user root May 23 10:23:31 volumio sudo[3880]: pam_unix(sudo:session): session closed for user root May 23 10:23:31 volumio volumio[1058]: error: MPD error: The expression evaluated to a falsy value: May 23 10:23:31 volumio volumio[1058]: assert.ok(self.idling) May 23 10:23:31 volumio volumio[1058]: error: The expression evaluated to a falsy value: May 23 10:23:31 volumio volumio[1058]: assert.ok(self.idling) May 23 10:23:31 volumio volumio[1058]: error: updateQueue error: null May 23 10:23:33 volumio sudo[3846]: pam_unix(sudo:session): session closed for user root May 23 10:23:33 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 10:23:33 volumio volumio[1058]: info: CoreStateMachine::getcurrentVolume May 23 10:23:33 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume May 23 10:23:33 volumio volumio[1058]: info: CoreStateMachine::pushState May 23 10:23:33 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:23:33 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState May 23 10:23:43 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork May 23 10:23:43 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo May 23 10:23:43 volumio sudo[3950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 10:23:43 volumio sudo[3950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:23:43 volumio sudo[3950]: pam_unix(sudo:session): session closed for user root May 23 10:23:57 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:23:59 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:01 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:03 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:05 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:07 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:09 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 23 10:24:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 23 10:24:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 23 10:24:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 23 10:24:11 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 23 10:24:11 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:13 volumio volumio[1058]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] May 23 10:24:13 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings May 23 10:24:13 volumio volumio[1058]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') May 23 10:24:13 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:15 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::Reload Ui May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 23 10:24:16 volumio volumio[1058]: info: Wizard terminated Successfully May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::volumiosetStartupVolume May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 10:24:16 volumio volumio[1058]: info: Player Reboot required after I2S DAC has been enabled in wizard May 23 10:24:16 volumio volumio[1058]: info: Executing Deferred Wifi Connection May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 23 10:24:16 volumio volumio[1058]: info: Saving new wireless network May 23 10:24:16 volumio sudo[3998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 23 10:24:16 volumio sudo[3998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:16 volumio sudo[3998]: pam_unix(sudo:session): session closed for user root May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 23 10:24:16 volumio volumio[1058]: info: Discovery: Restarting Advertising due to device name change May 23 10:24:16 volumio sudo[4005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 23 10:24:16 volumio sudo[4005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:16 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 23 10:24:16 volumio systemd[1]: Starting wireless.service - Wireless Services... May 23 10:24:16 volumio dbus-daemon[645]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.52' (uid=0 pid=4009 comm="timedatectl show --property=NTPSynchronized --valu") May 23 10:24:16 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 23 10:24:16 volumio dbus-daemon[645]: [system] Successfully activated service 'org.freedesktop.timedate1' May 23 10:24:16 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 23 10:24:16 volumio setdatetime-helper.sh[4008]: Time is already synchronized. May 23 10:24:16 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 23 10:24:16 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 23 10:24:16 volumio sudo[4024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 10:24:16 volumio sudo[4024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:16 volumio sudo[4024]: pam_unix(sudo:session): session closed for user root May 23 10:24:16 volumio sudo[4026]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 10:24:16 volumio sudo[4026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:16 volumio sudo[4026]: pam_unix(sudo:session): session closed for user root May 23 10:24:16 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.171 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState May 23 10:24:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 10:24:16 volumio volumio[1058]: info: Received Get System Info May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 10:24:16 volumio volumio[1058]: info: Discovery: Getting this device information May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState May 23 10:24:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState May 23 10:24:16 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:24:16 volumio volumio[1058]: info: Listing playlists May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 10:24:16 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 10:24:16 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... May 23 10:24:16 volumio dnsmasq[1776]: exiting on receipt of SIGTERM May 23 10:24:16 volumio systemd[1]: dnsmasq.service: Deactivated successfully. May 23 10:24:16 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. May 23 10:24:16 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... May 23 10:24:17 volumio volumio[1058]: info: Discovery: A device disappeared from network May 23 10:24:17 volumio volumio[1058]: info: Discovery: Device volumio disappeared from network May 23 10:24:17 volumio volumio[1058]: info: Discovery: A device disappeared from network May 23 10:24:17 volumio sudo[4056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 10:24:17 volumio sudo[4056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:17 volumio sudo[4056]: pam_unix(sudo:session): session closed for user root May 23 10:24:17 volumio sudo[4058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 10:24:17 volumio sudo[4058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:17 volumio sudo[4058]: pam_unix(sudo:session): session closed for user root May 23 10:24:17 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.171 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 23 10:24:17 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 10:24:17 volumio dhcpcd[834]: wlan0: carrier lost May 23 10:24:17 volumio avahi-daemon[643]: Withdrawing address record for 169.254.39.126 on wlan0. May 23 10:24:17 volumio dhcpcd[834]: wlan0: deleting route to 169.254.0.0/16 May 23 10:24:17 volumio dhcpcd[834]: wlan0: deleting default route May 23 10:24:17 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 23 10:24:17 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 23 10:24:17 volumio systemd[1]: welcome.service: Deactivated successfully. May 23 10:24:17 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 23 10:24:17 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 23 10:24:17 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 23 10:24:17 volumio systemd[1]: hostapd.service: Deactivated successfully. May 23 10:24:17 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. May 23 10:24:17 volumio welcome[4079]: Resolved ip:[1] 192.168.211.1 May 23 10:24:17 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 23 10:24:17 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 23 10:24:17 volumio sudo[4089]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 23 10:24:17 volumio sudo[4089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 10:24:17 volumio avahi-daemon[643]: Withdrawing address record for 192.168.211.1 on wlan0. May 23 10:24:17 volumio avahi-daemon[643]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 23 10:24:17 volumio avahi-daemon[643]: Interface wlan0.IPv4 no longer relevant for mDNS. May 23 10:24:17 volumio sudo[4089]: pam_unix(sudo:session): session closed for user root May 23 10:24:17 volumio sudo[4092]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 23 10:24:17 volumio sudo[4092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 10:24:17 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 23 10:24:17 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 23 10:24:17 volumio systemd[1]: welcome.service: Deactivated successfully. May 23 10:24:17 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 23 10:24:17 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 23 10:24:17 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 23 10:24:18 volumio welcome[4094]: Resolved ip:[0] May 23 10:24:18 volumio sudo[4092]: pam_unix(sudo:session): session closed for user root May 23 10:24:18 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 23 10:24:18 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 23 10:24:18 volumio sudo[4105]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 23 10:24:18 volumio sudo[4105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:18 volumio sudo[4105]: pam_unix(sudo:session): session closed for user root May 23 10:24:18 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 23 10:24:18 volumio sudo[4107]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 23 10:24:18 volumio sudo[4107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:18 volumio sudo[4107]: pam_unix(sudo:session): session closed for user root May 23 10:24:18 volumio sudo[4116]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 23 10:24:18 volumio sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:18 volumio sudo[4116]: pam_unix(sudo:session): session closed for user root May 23 10:24:18 volumio sudo[4118]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 23 10:24:18 volumio sudo[4118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:19 volumio ntpd[922]: IO: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=2, sent=2, dropped=0, active_time=1300 secs May 23 10:24:19 volumio ntpd[922]: IO: Deleting interface #6 wlan0, 169.254.39.126#123, interface stats: received=0, sent=0, dropped=0, active_time=1288 secs May 23 10:24:20 volumio sudo[4118]: pam_unix(sudo:session): session closed for user root May 23 10:24:20 volumio sudo[4124]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 23 10:24:20 volumio sudo[4124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:20 volumio sudo[4124]: pam_unix(sudo:session): session closed for user root May 23 10:24:20 volumio sudo[4126]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR May 23 10:24:20 volumio sudo[4126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:20 volumio sudo[4126]: pam_unix(sudo:session): session closed for user root May 23 10:24:20 volumio sudo[4134]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 23 10:24:20 volumio sudo[4134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 10:24:20 volumio sudo[4134]: pam_unix(sudo:session): session closed for user root May 23 10:24:20 volumio sudo[4136]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 23 10:24:20 volumio sudo[4136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 10:24:21 volumio volumio[1058]: info: Discovery: Started advertising with name: Volumio May 23 10:24:21 volumio sudo[4136]: pam_unix(sudo:session): session closed for user root May 23 10:24:21 volumio wpa_supplicant[4142]: Successfully initialized wpa_supplicant May 23 10:24:21 volumio sudo[4143]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 23 10:24:21 volumio sudo[4143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 23 10:24:21 volumio sudo[4143]: pam_unix(sudo:session): session closed for user root May 23 10:24:21 volumio dhcpcd[834]: control command: /sbin/dhcpcd May 23 10:24:21 volumio dhcpcd[834]: control_free: No such file or directory May 23 10:24:21 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 23 10:24:21 volumio dhcpcd[834]: lo: IAID 00:00:00:00 May 23 10:24:21 volumio dhcpcd[834]: lo: adding address fe80::78e8:a377:41cb:6e3a May 23 10:24:21 volumio dhcpcd[834]: ipv6_addaddr1: Permission denied May 23 10:24:21 volumio dhcpcd[834]: lo: using static address 127.0.0.1/8 May 23 10:24:21 volumio dhcpcd[834]: lo: adding route to 127.0.0.0/8 May 23 10:24:21 volumio systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. May 23 10:24:21 volumio wpa_supplicant[4142]: nl80211: kernel reports: Registration to specific type not supported May 23 10:24:21 volumio volumio[1058]: info: Discovery: adding 090dc706-b8a7-43ac-9313-71b113aa94e1 May 23 10:24:21 volumio volumio[1058]: info: Discovery: Found device Volumio May 23 10:24:21 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState May 23 10:24:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 May 23 10:24:22 volumio sudo[4177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:22 volumio sudo[4177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:22 volumio sudo[4177]: pam_unix(sudo:session): session closed for user root May 23 10:24:23 volumio sudo[4181]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:23 volumio sudo[4181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:23 volumio sudo[4181]: pam_unix(sudo:session): session closed for user root May 23 10:24:24 volumio wpa_supplicant[4173]: wlan0: Trying to associate with 10:7c:61:dd:f7:86 (SSID='Alex-wifi' freq=5520 MHz) May 23 10:24:24 volumio wpa_supplicant[4173]: wlan0: Associated with 10:7c:61:dd:f7:86 May 23 10:24:24 volumio wpa_supplicant[4173]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 23 10:24:24 volumio wpa_supplicant[4173]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR May 23 10:24:24 volumio wpa_supplicant[4173]: wlan0: WPA: Key negotiation completed with 10:7c:61:dd:f7:86 [PTK=CCMP GTK=CCMP] May 23 10:24:24 volumio wpa_supplicant[4173]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:7c:61:dd:f7:86 completed [id=0 id_str=] May 23 10:24:24 volumio dhcpcd[834]: wlan0: carrier acquired May 23 10:24:24 volumio dhcpcd[834]: wlan0: connected to Access Point: Alex-wifi May 23 10:24:24 volumio dhcpcd[834]: wlan0: IAID dd:fa:2d:48 May 23 10:24:24 volumio dhcpcd[834]: wlan0: soliciting an IPv6 router May 23 10:24:24 volumio sudo[4186]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:24 volumio sudo[4186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:24 volumio sudo[4186]: pam_unix(sudo:session): session closed for user root May 23 10:24:25 volumio sudo[4191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:25 volumio sudo[4191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:25 volumio sudo[4191]: pam_unix(sudo:session): session closed for user root May 23 10:24:25 volumio dhcpcd[834]: wlan0: soliciting a DHCP lease May 23 10:24:26 volumio sudo[4196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:26 volumio sudo[4196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:26 volumio sudo[4196]: pam_unix(sudo:session): session closed for user root May 23 10:24:26 volumio dhcpcd[834]: wlan0: offered 10.20.2.67 from 10.20.1.4 nshome May 23 10:24:26 volumio dhcpcd[834]: wlan0: probing address 10.20.2.67/24 May 23 10:24:27 volumio sudo[4215]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:27 volumio sudo[4215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:27 volumio sudo[4215]: pam_unix(sudo:session): session closed for user root May 23 10:24:28 volumio sudo[4219]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:28 volumio sudo[4219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:28 volumio sudo[4219]: pam_unix(sudo:session): session closed for user root May 23 10:24:29 volumio sudo[4223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:29 volumio sudo[4223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:29 volumio sudo[4223]: pam_unix(sudo:session): session closed for user root May 23 10:24:30 volumio sudo[4227]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:30 volumio sudo[4227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:30 volumio sudo[4227]: pam_unix(sudo:session): session closed for user root May 23 10:24:31 volumio sudo[4231]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 23 10:24:31 volumio sudo[4231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 23 10:24:31 volumio sudo[4231]: pam_unix(sudo:session): session closed for user root May 23 10:24:31 volumio dhcpcd[834]: wlan0: leased 10.20.2.67 for 600 seconds May 23 10:24:31 volumio avahi-daemon[643]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.20.2.67. May 23 10:24:31 volumio avahi-daemon[643]: New relevant interface wlan0.IPv4 for mDNS. May 23 10:24:31 volumio avahi-daemon[643]: Registering new address record for 10.20.2.67 on wlan0.IPv4. May 23 10:24:31 volumio dhcpcd[834]: wlan0: adding route to 10.20.2.0/24 May 23 10:24:31 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 23 10:24:31 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 23 10:24:31 volumio systemd[1]: welcome.service: Deactivated successfully. May 23 10:24:31 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 23 10:24:31 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 23 10:24:31 volumio dhcpcd[834]: wlan0: adding default route via 10.20.2.1 May 23 10:24:31 volumio volumio[1058]: compat.c: read() failed: Resource temporarily unavailable May 23 10:24:31 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 10:24:31 volumio volumio[1058]: Error: dns service error: unknown May 23 10:24:31 volumio volumio[1058]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 23 10:24:31 volumio volumio[1058]: errorCode: -65537 May 23 10:24:31 volumio volumio[1058]: } May 23 10:24:31 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 10:24:31 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 23 10:24:31 volumio welcome[4247]: Resolved ip:[1] 10.20.2.67 May 23 10:24:31 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 23 10:24:31 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 23 10:24:31 volumio sudo[4274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-23 10:23' May 23 10:24:31 volumio sudo[4274]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"