May 31 06:09:00 volumio volumio[1070]: info: Successfully Added MyVolumio device May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 06:09:00 volumio volumio[1070]: info: Updating MyVolumio device info May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:00 volumio volumio-remote-updater[742]: Test mode disabled May 31 06:09:00 volumio volumio-remote-updater[742]: Alpha mode disabled May 31 06:09:00 volumio volumio-remote-updater[742]: Alpha legacy test mode disabled May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 06:09:00 volumio volumio[1070]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 06:09:00 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 06:09:00 volumio volumio[1070]: info: Successfully Updated MyVolumio device May 31 06:09:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 31 06:09:03 volumio volumio[1070]: info: System name has changed, restarting Shairport Sync May 31 06:09:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:03 volumio volumio[1070]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 06:09:03 volumio volumio[1070]: info: Updating MyVolumio device info May 31 06:09:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:03 volumio volumio[1070]: info: Discovery: Restarting Advertising due to device name change May 31 06:09:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 31 06:09:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 06:09:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 06:09:04 volumio sudo[2333]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 31 06:09:04 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:04 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root May 31 06:09:04 volumio systemd[1]: upmpdcli.service: Deactivated successfully. May 31 06:09:04 volumio volumio[1070]: info: Starting Shairport Sync May 31 06:09:04 volumio sudo[2339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts May 31 06:09:04 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:04 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 06:09:04 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:04 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root May 31 06:09:04 volumio volumio[1070]: info: Permissions for /etc/hosts set May 31 06:09:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 06:09:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 06:09:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 06:09:04 volumio systemd[1]: shairport-sync.service: Consumed 1.677s CPU time. May 31 06:09:04 volumio volumio[1070]: info: Hostname now is volumio2 May 31 06:09:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 06:09:04 volumio sudo[2341]: pam_unix(sudo:session): session closed for user root May 31 06:09:04 volumio volumio[1070]: info: Shairport-Sync Started May 31 06:09:04 volumio volumio[1070]: info: Successfully Updated MyVolumio device May 31 06:09:04 volumio volumio[1070]: info: Discovery: A device disappeared from network May 31 06:09:05 volumio volumio[1070]: info: Discovery: A device disappeared from network May 31 06:09:05 volumio volumio[1070]: info: Discovery: A device disappeared from network May 31 06:09:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 31 06:09:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 06:09:06 volumio volumio-remote-updater[742]: Test mode disabled May 31 06:09:06 volumio volumio-remote-updater[742]: Alpha mode disabled May 31 06:09:06 volumio volumio-remote-updater[742]: Alpha legacy test mode disabled May 31 06:09:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 06:09:06 volumio volumio[1070]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 06:09:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 06:09:08 volumio volumio[1070]: info: Discovery: Started advertising with name: Volumio2 May 31 06:09:09 volumio sudo[2346]: volumio : unable to resolve host volumio: System error May 31 06:09:09 volumio sudo[2348]: volumio : unable to resolve host volumio: System error May 31 06:09:09 volumio sudo[2346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio2 May 31 06:09:09 volumio sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ May 31 06:09:09 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:09 volumio sudo[2346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:09 volumio2 sudo[2346]: pam_unix(sudo:session): session closed for user root May 31 06:09:09 volumio2 volumio[1070]: info: New hostname set May 31 06:09:09 volumio2 sudo[2348]: pam_unix(sudo:session): session closed for user root May 31 06:09:09 volumio2 volumio[1070]: info: Permissions for /etc/avahi/services/volumio.service May 31 06:09:09 volumio2 avahi-daemon[726]: Files changed, reloading. May 31 06:09:09 volumio2 avahi-daemon[726]: Loading service file /services/volumio.service. May 31 06:09:09 volumio2 volumio[1070]: info: Avahi name changed to volumio2 May 31 06:09:09 volumio2 volumio[1070]: info: Discovery: adding 29e25dda-0aae-47a4-be09-344f0bdf78cf May 31 06:09:09 volumio2 volumio[1070]: info: Discovery: Found device Volumio2 May 31 06:09:09 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:09 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:09 volumio2 volumio[1070]: info: Discovery: this is already registered, 29e25dda-0aae-47a4-be09-344f0bdf78cf May 31 06:09:09 volumio2 volumio[1070]: info: Discovery: Found device Volumio2 May 31 06:09:09 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:09 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:09 volumio2 volumio[1070]: info: Discovery: this is already registered, 29e25dda-0aae-47a4-be09-344f0bdf78cf May 31 06:09:09 volumio2 volumio[1070]: info: Discovery: Found device Volumio2 May 31 06:09:09 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:09 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:10 volumio2 avahi-daemon[726]: Service "Volumio2" (/services/volumio.service) successfully established. May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 31 06:09:11 volumio2 volumio[1070]: info: Preparing to save Alsa Options, stopping services first May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:11 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::volumioPause May 31 06:09:11 volumio2 volumio[1070]: info: CoreStateMachine::pause May 31 06:09:11 volumio2 volumio[1070]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 06:09:11 volumio2 volumio[1070]: info: Found match in Cards Database: setting mixer PCM for card Headphones May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 31 06:09:11 volumio2 volumio[1070]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 31 06:09:11 volumio2 volumio[1070]: info: Disabling external Volume Control May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:09:11 volumio2 volumio[1070]: info: Preparing to generate the ALSA configuration file May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 31 06:09:11 volumio2 sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 06:09:11 volumio2 sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:11 volumio2 sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 06:09:11 volumio2 sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:11 volumio2 sudo[2384]: pam_unix(sudo:session): session closed for user root May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 06:09:11 volumio2 volumio[1070]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 31 06:09:11 volumio2 volumio[1070]: wlan0 Interface doesn't support scanning : Device or resource busy May 31 06:09:11 volumio2 volumio[1070]: info: Cannot use regular scanning, forcing with ap-force May 31 06:09:11 volumio2 sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 31 06:09:11 volumio2 sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:11 volumio2 sudo[2411]: pam_unix(sudo:session): session closed for user root May 31 06:09:11 volumio2 volumio[1070]: command failed: Device or resource busy (-16) May 31 06:09:11 volumio2 volumio[1070]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 31 06:09:11 volumio2 volumio[1070]: command failed: Device or resource busy (-16) May 31 06:09:11 volumio2 volumio[1070]: info: VolumeController:: Volume=86 Mute =false May 31 06:09:11 volumio2 volumio[1070]: info: CoreStateMachine::pushState May 31 06:09:11 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::volumioPushState May 31 06:09:11 volumio2 volumio[1070]: info: Asound.conf file written May 31 06:09:11 volumio2 sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 06:09:11 volumio2 sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:11 volumio2 sudo[2416]: pam_unix(sudo:session): session closed for user root May 31 06:09:11 volumio2 volumio[1070]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 06:09:11 volumio2 volumio[1070]: No state is present for card b1 May 31 06:09:11 volumio2 volumio[1070]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 06:09:11 volumio2 volumio[1070]: /usr/share/alsa/init/bcm2835:14: control element not found May 31 06:09:11 volumio2 volumio[1070]: No state is present for card b1 May 31 06:09:11 volumio2 volumio[1070]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 06:09:11 volumio2 volumio[1070]: No state is present for card Headphones May 31 06:09:11 volumio2 volumio[1070]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 06:09:11 volumio2 volumio[1070]: /usr/share/alsa/init/bcm2835:20: control element not found May 31 06:09:11 volumio2 volumio[1070]: No state is present for card Headphones May 31 06:09:11 volumio2 volumio[1070]: info: Output device has changed, restarting MPD May 31 06:09:11 volumio2 volumio[1070]: info: Output device has changed, restarting Shairport Sync May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:11 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:11 volumio2 sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 06:09:11 volumio2 sudo[2423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:11 volumio2 sudo[2424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 06:09:12 volumio2 sudo[2424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:12 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 06:09:12 volumio2 sudo[2424]: pam_unix(sudo:session): session closed for user root May 31 06:09:12 volumio2 volumio[1070]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 06:09:12 volumio2 volumio[1070]: info: Output device has changed, restarting MPD May 31 06:09:12 volumio2 volumio[1070]: info: Output device has changed, restarting Shairport Sync May 31 06:09:12 volumio2 sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 06:09:12 volumio2 sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:12 volumio2 sudo[2432]: pam_unix(sudo:session): session closed for user root May 31 06:09:12 volumio2 volumio[1070]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 06:09:12 volumio2 sudo[2434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 06:09:12 volumio2 sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:12 volumio2 volumio[1070]: info: MPD Permissions set May 31 06:09:12 volumio2 volumio[1070]: info: MPD Permissions set May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 systemd[1]: mpd.service: Deactivated successfully. May 31 06:09:12 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 06:09:12 volumio2 systemd[1]: mpd.service: Consumed 3.208s CPU time. May 31 06:09:12 volumio2 systemd[1]: mpd.socket: Deactivated successfully. May 31 06:09:12 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 06:09:12 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:12 volumio2 volumio[1070]: info: Starting Shairport Sync May 31 06:09:12 volumio2 volumio[1070]: info: Starting Shairport Sync May 31 06:09:12 volumio2 sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 06:09:12 volumio2 sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:12 volumio2 sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 06:09:12 volumio2 sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:12 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 06:09:12 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... May 31 06:09:12 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 06:09:12 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 31 06:09:12 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 06:09:12 volumio2 systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. May 31 06:09:12 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 06:09:12 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 06:09:12 volumio2 sudo[2455]: pam_unix(sudo:session): session closed for user root May 31 06:09:12 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. May 31 06:09:12 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 06:09:12 volumio2 volumio[1070]: info: Shairport-Sync Started May 31 06:09:12 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 06:09:12 volumio2 sudo[2457]: pam_unix(sudo:session): session closed for user root May 31 06:09:12 volumio2 sudo[2460]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 06:09:12 volumio2 sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 06:09:12 volumio2 sudo[2460]: pam_unix(sudo:session): session closed for user root May 31 06:09:12 volumio2 volumio[1070]: info: Shairport-Sync Started May 31 06:09:13 volumio2 mpd[2478]: 2025-05-31T06:09:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 06:09:13 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. May 31 06:09:13 volumio2 sudo[2434]: pam_unix(sudo:session): session closed for user root May 31 06:09:13 volumio2 sudo[2423]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 sudo[2482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf May 31 06:09:14 volumio2 sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 volumio[1070]: error: MPD error: The expression evaluated to a falsy value: May 31 06:09:14 volumio2 volumio[1070]: assert.ok(self.idling) May 31 06:09:14 volumio2 volumio[1070]: error: The expression evaluated to a falsy value: May 31 06:09:14 volumio2 volumio[1070]: assert.ok(self.idling) May 31 06:09:14 volumio2 volumio[1070]: error: updateQueue error: null May 31 06:09:14 volumio2 sudo[2482]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 volumio[1070]: info: smb.conf Permissions set May 31 06:09:14 volumio2 sudo[2485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL May 31 06:09:14 volumio2 sudo[2485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 sudo[2485]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 volumio[1070]: info: Internal perms successfully set May 31 06:09:14 volumio2 sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:09:14 volumio2 sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 sudo[2487]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:09:14 volumio2 sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 sudo[2489]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 sudo[2495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service May 31 06:09:14 volumio2 sudo[2495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 systemd[1]: Stopping nmbd.service - Samba NMB Daemon... May 31 06:09:14 volumio2 nmbd[979]: [2025/05/31 06:09:14.249746, 0] ../../source3/nmbd/nmbd.c:59(terminate) May 31 06:09:14 volumio2 nmbd[979]: Got SIGTERM: going down... May 31 06:09:14 volumio2 systemd[1]: nmbd.service: Deactivated successfully. May 31 06:09:14 volumio2 systemd[1]: Stopped nmbd.service - Samba NMB Daemon. May 31 06:09:14 volumio2 sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 06:09:14 volumio2 sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 systemd[1]: Starting nmbd.service - Samba NMB Daemon... May 31 06:09:14 volumio2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 31 06:09:14 volumio2 sudo[2497]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 volumio[1070]: info: Upmpdcli Daemon Started May 31 06:09:14 volumio2 upmpdcli[2517]: writing RSA key May 31 06:09:14 volumio2 nmbd[2524]: [2025/05/31 06:09:14.712936, 0] ../../source3/nmbd/nmbd.c:901(main) May 31 06:09:14 volumio2 nmbd[2524]: nmbd version 4.17.8-Raspbian started. May 31 06:09:14 volumio2 nmbd[2524]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 31 06:09:14 volumio2 nmbd[2524]: [2025/05/31 06:09:14.718398, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 31 06:09:14 volumio2 nmbd[2524]: started asyncdns process 2525 May 31 06:09:14 volumio2 systemd[1]: Started nmbd.service - Samba NMB Daemon. May 31 06:09:14 volumio2 sudo[2495]: pam_unix(sudo:session): session closed for user root May 31 06:09:14 volumio2 nmbd[2524]: [2025/05/31 06:09:14.739055, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 31 06:09:14 volumio2 nmbd[2524]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.137 for name WORKGROUP<1d>. May 31 06:09:14 volumio2 nmbd[2524]: This response was from IP 192.168.178.26, reporting an IP address of 192.168.178.26. May 31 06:09:14 volumio2 sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service May 31 06:09:14 volumio2 sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:14 volumio2 systemd[1]: Stopping smbd.service - Samba SMB Daemon... May 31 06:09:14 volumio2 systemd[1]: smbd.service: Deactivated successfully. May 31 06:09:14 volumio2 systemd[1]: Stopped smbd.service - Samba SMB Daemon. May 31 06:09:14 volumio2 systemd[1]: Starting smbd.service - Samba SMB Daemon... May 31 06:09:15 volumio2 sudo[2381]: pam_unix(sudo:session): session closed for user root May 31 06:09:15 volumio2 smbd[2532]: [2025/05/31 06:09:15.086903, 0] ../../source3/smbd/server.c:1741(main) May 31 06:09:15 volumio2 smbd[2532]: smbd version 4.17.8-Raspbian started. May 31 06:09:15 volumio2 smbd[2532]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 31 06:09:15 volumio2 systemd[1]: Started smbd.service - Samba SMB Daemon. May 31 06:09:15 volumio2 sudo[2527]: pam_unix(sudo:session): session closed for user root May 31 06:09:15 volumio2 volumio[1070]: info: SAMBA Restarted May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 31 06:09:15 volumio2 volumio[1070]: info: CoreStateMachine::getcurrentVolume May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume May 31 06:09:15 volumio2 volumio[1070]: info: VolumeController:: Volume=86 Mute =false May 31 06:09:15 volumio2 volumio[1070]: info: CoreStateMachine::pushState May 31 06:09:15 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::volumioPushState May 31 06:09:15 volumio2 volumio[1070]: info: CoreStateMachine::updateTrackBlock May 31 06:09:15 volumio2 volumio[1070]: info: CorePlayQueue::getTrackBlock May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume May 31 06:09:15 volumio2 volumio[1070]: info: VolumeController:: Volume=86 Mute =false May 31 06:09:15 volumio2 volumio[1070]: info: CoreStateMachine::pushState May 31 06:09:15 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::volumioPushState May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:09:15 volumio2 volumio[1070]: info: Retrieving Cloud Streaming UI May 31 06:09:15 volumio2 volumio[1070]: info: Getting Tidal Cloud Configuration May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:09:15 volumio2 volumio[1070]: info: Getting Qobuz Cloud Configuration May 31 06:09:15 volumio2 volumio[1070]: info: Asking plugin for UI Config May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:09:15 volumio2 volumio[1070]: info: Getting Spotify Cloud Configuration May 31 06:09:15 volumio2 volumio[1070]: info: Asking plugin for UI Config May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:09:15 volumio2 volumio[1070]: info: Saving Spotify Acccount May 31 06:09:15 volumio2 volumio[1070]: info: Got Tidal Cloud Configuration May 31 06:09:15 volumio2 volumio[1070]: info: Got it May 31 06:09:15 volumio2 volumio[1070]: info: Got it May 31 06:09:15 volumio2 volumio[1070]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 06:09:15 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:09:15 volumio2 volumio[1070]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 31 06:09:17 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 31 06:09:17 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 31 06:09:17 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 31 06:09:17 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 31 06:09:17 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 31 06:09:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 31 06:09:19 volumio2 volumio[1070]: info: CoreCommandRouter::Reload Ui May 31 06:09:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 31 06:09:19 volumio2 volumio[1070]: info: Wizard terminated Successfully May 31 06:09:19 volumio2 volumio[1070]: info: CoreCommandRouter::volumiosetStartupVolume May 31 06:09:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:09:20 volumio2 sudo[2555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:09:20 volumio2 sudo[2555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:20 volumio2 sudo[2555]: pam_unix(sudo:session): session closed for user root May 31 06:09:20 volumio2 sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:09:20 volumio2 sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:20 volumio2 sudo[2553]: pam_unix(sudo:session): session closed for user root May 31 06:09:20 volumio2 volumio[1070]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 May 31 06:09:20 volumio2 sudo[2560]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:09:20 volumio2 sudo[2560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:20 volumio2 sudo[2560]: pam_unix(sudo:session): session closed for user root May 31 06:09:21 volumio2 sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:09:21 volumio2 sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:09:21 volumio2 sudo[2562]: pam_unix(sudo:session): session closed for user root May 31 06:09:21 volumio2 volumio[1070]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 6 May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:21 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 06:09:21 volumio2 volumio[1070]: info: Received Get System Info May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:09:21 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:21 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:21 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:21 volumio2 volumio[1070]: info: Listing playlists May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 06:09:21 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:22 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:09:22 volumio2 volumio[1070]: info: Received Get System Info May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:09:22 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:22 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:22 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:09:24 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:09:24 volumio2 volumio[1070]: info: Received Get System Info May 31 06:09:24 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:09:24 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:09:24 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:09:24 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:24 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:24 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:09:24 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse May 31 06:09:26 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 31 06:09:26 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 06:09:26 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. May 31 06:09:26 volumio2 volumio-remote-updater[742]: Test mode disabled May 31 06:09:26 volumio2 volumio-remote-updater[742]: Alpha mode disabled May 31 06:09:26 volumio2 volumio-remote-updater[742]: Alpha legacy test mode disabled May 31 06:09:26 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 06:09:26 volumio2 volumio[1070]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 06:09:26 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 06:09:28 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:09:28 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 06:09:31 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 06:09:35 volumio2 volumio[1070]: info: CoreCommandRouter::Close All Modals sent May 31 06:09:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 31 06:09:38 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 06:09:38 volumio2 volumio[1070]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 06:09:38 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 06:09:39 volumio2 volumio[1070]: info: Received Get System Version May 31 06:09:39 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:09:39 volumio2 volumio[1070]: info: Received Get System Info May 31 06:09:39 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:09:39 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:09:39 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:09:39 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:09:39 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:09:39 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:10:06 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:06 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:07 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:07 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:10:08 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:10:08 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:10:08 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 31 06:10:08 volumio2 volumio[1070]: info: Received Get System Version May 31 06:10:08 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:10:16 volumio2 volumio[1070]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] May 31 06:10:16 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI May 31 06:10:16 volumio2 volumio[1070]: info: Setting active UI to: {"uiPrettyName":"Classic","uiName":"classic","uiPath":"/volumio/http/www"} May 31 06:10:17 volumio2 volumio[1070]: info: CoreCommandRouter::Reload Ui May 31 06:10:17 volumio2 sudo[2681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:10:17 volumio2 sudo[2681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:10:18 volumio2 sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:10:18 volumio2 sudo[2681]: pam_unix(sudo:session): session closed for user root May 31 06:10:18 volumio2 sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:10:18 volumio2 sudo[2683]: pam_unix(sudo:session): session closed for user root May 31 06:10:18 volumio2 volumio[1070]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 6 May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:18 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 31 06:10:18 volumio2 volumio[1070]: info: Received Get System Version May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:10:18 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds May 31 06:10:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:10:19 volumio2 volumio[1070]: info: Received Get System Info May 31 06:10:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:10:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:10:19 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:10:19 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:19 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:19 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:10:20 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:10:20 volumio2 volumio[1070]: info: Received Get System Info May 31 06:10:20 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:10:20 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:10:20 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:10:20 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:20 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:20 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:27 volumio2 volumio[1070]: info: Retrieving Cloud Streaming UI May 31 06:10:27 volumio2 volumio[1070]: info: Getting Tidal Cloud Configuration May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:27 volumio2 volumio[1070]: info: Getting Qobuz Cloud Configuration May 31 06:10:27 volumio2 volumio[1070]: info: Asking plugin for UI Config May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:27 volumio2 volumio[1070]: info: Getting Spotify Cloud Configuration May 31 06:10:27 volumio2 volumio[1070]: info: Asking plugin for UI Config May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:27 volumio2 volumio[1070]: info: Saving Spotify Acccount May 31 06:10:27 volumio2 volumio[1070]: info: Got Tidal Cloud Configuration May 31 06:10:27 volumio2 volumio[1070]: info: Got it May 31 06:10:27 volumio2 volumio[1070]: info: Got it May 31 06:10:27 volumio2 volumio[1070]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:10:27 volumio2 volumio[1070]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetBrowseSources May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetBrowseSources May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetBrowseSources May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:27 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 31 06:10:31 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:33 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 31 06:10:33 volumio2 dbus-daemon[729]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=2740 comm="timedatectl show --property=NTPSynchronized --valu") May 31 06:10:33 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 31 06:10:33 volumio2 dbus-daemon[729]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 06:10:33 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 31 06:10:33 volumio2 setdatetime-helper.sh[2739]: Time is not synchronized. Attempting to sync... May 31 06:10:33 volumio2 sudo[2748]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 31 May 2025 05:10:33 GMT#015' May 31 06:10:33 volumio2 sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 06:10:33 volumio2 setdatetime-helper.sh[2749]: Sat May 31 06:10:33 +01 2025 May 31 06:10:33 volumio2 sudo[2748]: pam_unix(sudo:session): session closed for user root May 31 06:10:33 volumio2 setdatetime-helper.sh[2739]: Time synchronized successfully. May 31 06:10:33 volumio2 systemd-journald[363]: Time jumped backwards, rotating. May 31 06:10:33 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 31 06:10:33 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 31 06:10:34 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 06:10:35 volumio2 volumio[1070]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 06:10:35 volumio2 volumio[1070]: info: Received Get System Version May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:10:35 volumio2 volumio[1070]: info: Received Get System Info May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:10:35 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:35 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:35 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:10:38 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:42 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:45 volumio2 volumio[1070]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] May 31 06:10:45 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility May 31 06:10:45 volumio2 volumio[1070]: info: CoreCommandRouter::volumioUpdateToBrowseSources May 31 06:10:45 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:10:46 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:50 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 06:10:52 volumio2 sudo[2782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:10:52 volumio2 sudo[2781]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:10:52 volumio2 sudo[2781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:10:52 volumio2 sudo[2781]: pam_unix(sudo:session): session closed for user root May 31 06:10:52 volumio2 sudo[2782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:10:52 volumio2 sudo[2782]: pam_unix(sudo:session): session closed for user root May 31 06:10:52 volumio2 volumio[1070]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 May 31 06:10:52 volumio2 sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:10:52 volumio2 sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:10:52 volumio2 sudo[2787]: pam_unix(sudo:session): session closed for user root May 31 06:10:52 volumio2 sudo[2790]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:10:52 volumio2 sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 06:10:52 volumio2 sudo[2790]: pam_unix(sudo:session): session closed for user root May 31 06:10:52 volumio2 volumio[1070]: verbose: New Socket.io Connection to 192.168.178.137 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 May 31 06:10:52 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:52 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:52 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 06:10:52 volumio2 volumio[1070]: info: Listing playlists May 31 06:10:52 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 06:10:52 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 06:10:52 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 06:10:52 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 06:10:54 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:10:54 volumio2 volumio[1070]: info: Received Get System Info May 31 06:10:54 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:10:54 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:10:54 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:10:54 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:54 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:54 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:10:55 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:10:55 volumio2 volumio[1070]: info: Received Get System Info May 31 06:10:55 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:10:55 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:10:55 volumio2 volumio[1070]: info: Discovery: Getting this device information May 31 06:10:55 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetState May 31 06:10:55 volumio2 volumio[1070]: info: CorePlayQueue::getTrack 0 May 31 06:10:55 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:10:56 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources May 31 06:10:56 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:10:56 volumio2 volumio[1070]: info: CoreCommandRouter::volumioGetQueue May 31 06:10:56 volumio2 volumio[1070]: info: CoreStateMachine::getQueue May 31 06:10:56 volumio2 volumio[1070]: info: CorePlayQueue::getQueue May 31 06:10:57 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 06:10:57 volumio2 volumio[1070]: info: Preload queue cleared May 31 06:10:58 volumio2 volumio[1070]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 06:10:59 volumio2 volumio[1070]: Use XMLElement.setAttribute instead May 31 06:10:59 volumio2 volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 06:10:59 volumio2 volumio[1070]: TypeError: children[i].attr(...).value is not a function May 31 06:10:59 volumio2 volumio[1070]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 May 31 06:10:59 volumio2 volumio[1070]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 06:10:59 volumio2 volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 06:10:59 volumio2 sudo[2808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 06:09' May 31 06:10:59 volumio2 sudo[2808]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"