-- Logs begin at Thu 2019-02-14 10:12:00 GMT, end at Mon 2025-09-01 12:49:08 BST. -- Sep 01 12:48:13 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 12:48:13 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:13 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:13 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 12:48:13 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:13 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:13 volumio volumio[657]: verbose: New Socket.io Connection to 192.168.86.35 from 192.168.86.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 01 12:48:13 volumio volumio[657]: info: Received Get System Info Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 12:48:13 volumio volumio[657]: info: Discovery: Getting this device information Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:48:13 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 12:48:13 volumio volumio-remote-updater[446]: No test mode Sep 01 12:48:13 volumio volumio-remote-updater[446]: No alpha test mode Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:13 volumio volumio[657]: info: Retrieving Cloud Streaming UI Sep 01 12:48:13 volumio volumio[657]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 01 12:48:13 volumio volumio[657]: info: Getting Tidal Cloud Configuration Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:13 volumio volumio[657]: info: Getting Qobuz Cloud Configuration Sep 01 12:48:13 volumio volumio[657]: info: Asking plugin for UI Config Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:13 volumio volumio[657]: info: Getting Spotify Cloud Configuration Sep 01 12:48:13 volumio volumio[657]: info: Asking plugin for UI Config Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:13 volumio volumio[657]: info: Saving Spotify Acccount Sep 01 12:48:13 volumio volumio[657]: info: Got Tidal Cloud Configuration Sep 01 12:48:13 volumio volumio[657]: info: Got it Sep 01 12:48:13 volumio volumio[657]: info: Got it Sep 01 12:48:13 volumio volumio[657]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 12:48:13 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:13 volumio volumio[657]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 12:48:14 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 01 12:48:14 volumio volumio[657]: info: Loading i18n strings for locale en Sep 01 12:48:14 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 01 12:48:14 volumio volumio[657]: info: Setting timezone to Europe/London Sep 01 12:48:14 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 01 12:48:14 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:14 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:14 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Sep 01 12:48:14 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:15 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:15 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 01 12:48:15 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:15 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:15 volumio sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Sep 01 12:48:15 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:15 volumio dbus-daemon[457]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=1370 comm="/usr/bin/timedatectl set-timezone Europe/London ") Sep 01 12:48:15 volumio systemd[1]: Starting Time & Date Service... Sep 01 12:48:15 volumio dbus-daemon[457]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 12:48:15 volumio systemd[1]: Started Time & Date Service. Sep 01 12:48:15 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:15 volumio volumio[657]: info: Fetching Streaming Services browse cache Sep 01 12:48:15 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 12:48:15 volumio volumio[657]: Updating browse sources language Sep 01 12:48:15 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:48:20 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 01 12:48:20 volumio volumio[657]: info: Loading i18n strings for locale en Sep 01 12:48:20 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 01 12:48:20 volumio volumio[657]: info: Setting timezone to Europe/London Sep 01 12:48:20 volumio sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 01 12:48:20 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:21 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:21 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Sep 01 12:48:21 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:21 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:21 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 01 12:48:21 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:21 volumio sudo[1396]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:21 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Sep 01 12:48:21 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:21 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:21 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 01 12:48:21 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 01 12:48:21 volumio volumio[657]: info: Fetching Streaming Services browse cache Sep 01 12:48:21 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 12:48:21 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:21 volumio volumio[657]: Updating browse sources language Sep 01 12:48:21 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:48:21 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:21 volumio volumio[657]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 01 12:48:21 volumio volumio[657]: wlan0 Interface doesn't support scanning. Sep 01 12:48:21 volumio volumio[657]: info: Cannot use regular scanning, forcing with ap-force Sep 01 12:48:21 volumio sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 01 12:48:21 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:21 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:21 volumio volumio[657]: command failed: No such device (-19) Sep 01 12:48:21 volumio volumio[657]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 01 12:48:21 volumio volumio[657]: command failed: No such device (-19) Sep 01 12:48:27 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Sep 01 12:48:27 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Sep 01 12:48:27 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 01 12:48:27 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:27 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 12:48:31 volumio volumio[657]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 01 12:48:31 volumio volumio[657]: info: Received Get System Version Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 12:48:31 volumio volumio[657]: info: Received Get System Info Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 12:48:31 volumio volumio[657]: info: Discovery: Getting this device information Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:48:31 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:31 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 12:48:45 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 01 12:48:45 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 12:48:45 volumio volumio-remote-updater[446]: No test mode Sep 01 12:48:45 volumio volumio-remote-updater[446]: No alpha test mode Sep 01 12:48:45 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 01 12:48:45 volumio volumio[657]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 01 12:48:45 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 01 12:48:46 volumio volumio[657]: info: Preparing to save Alsa Options, stopping services first Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:48:46 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::volumioPause Sep 01 12:48:46 volumio volumio[657]: info: CoreStateMachine::pause Sep 01 12:48:46 volumio volumio[657]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"iqaudio-dacplus","label":"IQaudIO DAC Plus"},"output_device":{"value":1,"label":"IQaudIO DAC Plus"},"disallowPush":true} Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:46 volumio volumio[657]: info: Enabling I2S DAC: IQaudIO DAC Plus Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:46 volumio sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Sep 01 12:48:46 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:46 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:46 volumio volumio[657]: info: No Overlays Loaded Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Sep 01 12:48:46 volumio sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay iqaudio-dacplus Sep 01 12:48:46 volumio sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Sep 01 12:48:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Sep 01 12:48:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Sep 01 12:48:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Sep 01 12:48:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/mute-gpios Sep 01 12:48:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Sep 01 12:48:46 volumio volumio[657]: warn: Unable to locate the device IQaudIO DAC Plus a reboot may be required. Sep 01 12:48:46 volumio sudo[1449]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:46 volumio volumio[657]: info: Found match in i2s Card Database: setting mixer Digital for card IQaudIO DAC Plus Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 01 12:48:46 volumio volumio[657]: info: Updating Volume Controller Parameters: Device: 2 Name: IQaudIO DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 01 12:48:46 volumio volumio[657]: info: Disabling external Volume Control Sep 01 12:48:46 volumio systemd-udevd[1460]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 01 12:48:46 volumio volumio[657]: info: Preparing to generate the ALSA configuration file Sep 01 12:48:46 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:46 volumio volumio[657]: info: Retrieving Cloud Streaming UI Sep 01 12:48:47 volumio volumio[657]: info: Data script executed Sep 01 12:48:47 volumio volumio[657]: info: I2S Param [object Object] successfully enabled Sep 01 12:48:47 volumio volumio[657]: info: Getting Tidal Cloud Configuration Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:47 volumio volumio[657]: info: Getting Qobuz Cloud Configuration Sep 01 12:48:47 volumio volumio[657]: info: Asking plugin for UI Config Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:47 volumio volumio[657]: info: Getting Spotify Cloud Configuration Sep 01 12:48:47 volumio volumio[657]: info: Asking plugin for UI Config Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:47 volumio volumio[657]: info: Saving Spotify Acccount Sep 01 12:48:47 volumio volumio[657]: info: Got Tidal Cloud Configuration Sep 01 12:48:47 volumio volumio[657]: info: Got it Sep 01 12:48:47 volumio volumio[657]: info: Got it Sep 01 12:48:47 volumio volumio[657]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 12:48:47 volumio volumio[657]: info: Getting Alsa Cards List without I2S DAC Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 12:48:47 volumio volumio[657]: info: VolumeController:: Volume=100 Mute =false Sep 01 12:48:47 volumio volumio[657]: info: CoreStateMachine::pushState Sep 01 12:48:47 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::volumioPushState Sep 01 12:48:47 volumio volumio[657]: info: Asound.conf file written Sep 01 12:48:47 volumio sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 01 12:48:47 volumio sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:47 volumio sudo[1499]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:47 volumio volumio[657]: No state is present for card b1 Sep 01 12:48:47 volumio volumio[657]: /usr/share/alsa/init/bcm2835:14: control element not found Sep 01 12:48:47 volumio volumio[657]: No state is present for card b1 Sep 01 12:48:47 volumio volumio[657]: No state is present for card Headphones Sep 01 12:48:47 volumio volumio[657]: /usr/share/alsa/init/bcm2835:20: control element not found Sep 01 12:48:47 volumio volumio[657]: No state is present for card Headphones Sep 01 12:48:47 volumio volumio[657]: No state is present for card IQaudIODAC Sep 01 12:48:47 volumio volumio[657]: Found hardware: "IQaudIODAC" "" "" "" "" Sep 01 12:48:47 volumio volumio[657]: Hardware is initialized using a generic method Sep 01 12:48:47 volumio volumio[657]: No state is present for card IQaudIODAC Sep 01 12:48:47 volumio volumio[657]: info: Output device has changed, restarting MPD Sep 01 12:48:47 volumio volumio[657]: info: Output device has changed, restarting Shairport Sync Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:47 volumio sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 12:48:47 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:47 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:47 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 12:48:47 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:47 volumio systemd[1]: Stopping Music Player Daemon... Sep 01 12:48:47 volumio volumio[657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 12:48:47 volumio volumio[657]: info: Output device has changed, restarting MPD Sep 01 12:48:47 volumio systemd[1]: mpd.service: Succeeded. Sep 01 12:48:47 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 12:48:47 volumio volumio[657]: info: Output device has changed, restarting Shairport Sync Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:47 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:47 volumio sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 12:48:47 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:47 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:47 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 12:48:47 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:47 volumio systemd[1]: Starting Music Player Daemon... Sep 01 12:48:48 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 01 12:48:48 volumio systemd[1]: mpd.service: Succeeded. Sep 01 12:48:48 volumio systemd[1]: Stopped Music Player Daemon. Sep 01 12:48:48 volumio volumio[657]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 12:48:48 volumio systemd[1]: Starting Music Player Daemon... Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 12:48:48 volumio volumio[657]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 12:48:48 volumio volumio[657]: info: MPD Permissions set Sep 01 12:48:48 volumio volumio[657]: info: MPD Permissions set Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:48 volumio volumio[657]: info: Starting Shairport Sync Sep 01 12:48:48 volumio volumio[657]: info: Starting Shairport Sync Sep 01 12:48:48 volumio sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 12:48:48 volumio sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:48 volumio sudo[1522]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 12:48:48 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:48 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:48 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 12:48:48 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 12:48:48 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 01 12:48:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 12:48:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 12:48:48 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:48 volumio sudo[1532]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:48 volumio volumio[657]: info: Shairport-Sync Started Sep 01 12:48:48 volumio volumio[657]: info: Shairport-Sync Started Sep 01 12:48:50 volumio mpd[1538]: Sep 01 12:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 12:48:50 volumio systemd[1]: Started Music Player Daemon. Sep 01 12:48:50 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:50 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:50 volumio volumio[657]: error: MPD error: The expression evaluated to a falsy value: Sep 01 12:48:50 volumio volumio[657]: assert.ok(self.idling) Sep 01 12:48:50 volumio volumio[657]: error: The expression evaluated to a falsy value: Sep 01 12:48:50 volumio volumio[657]: assert.ok(self.idling) Sep 01 12:48:50 volumio volumio[657]: error: updateQueue error: null Sep 01 12:48:50 volumio volumio[657]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 01 12:48:50 volumio volumio[657]: info: CoreStateMachine::getcurrentVolume Sep 01 12:48:50 volumio volumio[657]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 12:48:51 volumio volumio[657]: info: VolumeController:: Volume=100 Mute =false Sep 01 12:48:51 volumio volumio[657]: info: CoreStateMachine::pushState Sep 01 12:48:51 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::volumioPushState Sep 01 12:48:51 volumio volumio[657]: info: CoreStateMachine::updateTrackBlock Sep 01 12:48:51 volumio volumio[657]: info: CorePlayQueue::getTrackBlock Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 12:48:51 volumio volumio[657]: info: VolumeController:: Volume=100 Mute =false Sep 01 12:48:51 volumio volumio[657]: info: CoreStateMachine::pushState Sep 01 12:48:51 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::volumioPushState Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 12:48:51 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 01 12:48:51 volumio volumio-remote-updater[446]: No test mode Sep 01 12:48:51 volumio volumio-remote-updater[446]: No alpha test mode Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 01 12:48:51 volumio volumio[657]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 01 12:48:51 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 01 12:48:53 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Sep 01 12:48:53 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 01 12:48:53 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Sep 01 12:48:53 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Sep 01 12:48:53 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 01 12:48:57 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 01 12:48:57 volumio volumio[657]: info: CoreCommandRouter::Reload Ui Sep 01 12:48:57 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Sep 01 12:48:57 volumio volumio[657]: info: Wizard terminated Successfully Sep 01 12:48:57 volumio volumio[657]: info: CoreCommandRouter::volumiosetStartupVolume Sep 01 12:48:57 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 12:48:57 volumio volumio[657]: info: Player Reboot required after I2S DAC has been enabled in wizard Sep 01 12:48:58 volumio sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 12:48:58 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:58 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:58 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 12:48:58 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:58 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:58 volumio volumio[657]: verbose: New Socket.io Connection to 192.168.86.35 from 192.168.86.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 01 12:48:58 volumio sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 12:48:58 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:58 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:58 volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 12:48:58 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 12:48:58 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Sep 01 12:48:58 volumio volumio[657]: verbose: New Socket.io Connection to 192.168.86.35 from 192.168.86.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 01 12:48:58 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:48:59 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:48:59 volumio volumio[657]: info: CoreStateMachine::getQueue Sep 01 12:48:59 volumio volumio[657]: info: CorePlayQueue::getQueue Sep 01 12:48:59 volumio volumio[657]: info: Listing playlists Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 12:48:59 volumio volumio[657]: info: Received Get System Info Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 12:48:59 volumio volumio[657]: info: Discovery: Getting this device information Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:48:59 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:48:59 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 12:48:59 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 12:49:00 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 12:49:00 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 12:49:00 volumio volumio[657]: info: Received Get System Info Sep 01 12:49:00 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 12:49:00 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 12:49:00 volumio volumio[657]: info: Discovery: Getting this device information Sep 01 12:49:00 volumio volumio[657]: info: CoreCommandRouter::volumioGetState Sep 01 12:49:00 volumio volumio[657]: info: CorePlayQueue::getTrack 0 Sep 01 12:49:00 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 12:49:07 volumio volumio[657]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 12:49:07 volumio volumio[657]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 12:49:07 volumio volumio[657]: TypeError: Cannot read property 'length' of undefined Sep 01 12:49:07 volumio volumio[657]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 01 12:49:07 volumio volumio[657]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 01 12:49:07 volumio volumio[657]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 01 12:49:07 volumio volumio[657]: at Parser.emit (events.js:315:20) Sep 01 12:49:07 volumio volumio[657]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 01 12:49:07 volumio volumio[657]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 01 12:49:07 volumio volumio[657]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 01 12:49:07 volumio volumio[657]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 01 12:49:07 volumio volumio[657]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 01 12:49:07 volumio volumio[657]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 01 12:49:07 volumio volumio[657]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 01 12:49:07 volumio volumio[657]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 01 12:49:07 volumio volumio[657]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 01 12:49:07 volumio volumio[657]: at IncomingMessage.emit (events.js:327:22) Sep 01 12:49:07 volumio volumio[657]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 01 12:49:07 volumio volumio[657]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 01 12:49:07 volumio volumio[657]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 12:49:08 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 12:48 Sep 01 12:49:08 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"