Dec 26 13:43:42 volumio hostapd[1986]: wlan0: STA 5a:0e:3b:b2:fb:a7 IEEE 802.11: associated Dec 26 13:43:42 volumio hostapd[1986]: wlan0: STA 5a:0e:3b:b2:fb:a7 RADIUS: starting accounting session BB8EB0DE32FC4681 Dec 26 13:43:47 volumio sudo[2311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 13:43:47 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:47 volumio sudo[2313]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:43:47 volumio sudo[2313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:47 volumio sudo[2313]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:47 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:47 volumio volumio[1315]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 26 13:43:47 volumio volumio[1315]: info: Received Get System Info Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 13:43:47 volumio volumio[1315]: info: Discovery: Getting this device information Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 26 13:43:47 volumio volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 13:43:47 volumio volumio-remote-updater[982]: Test mode disabled Dec 26 13:43:47 volumio volumio-remote-updater[982]: Alpha mode disabled Dec 26 13:43:47 volumio volumio-remote-updater[982]: Alpha legacy test mode disabled Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 13:43:47 volumio volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:43:47 volumio volumio-remote-updater[982]: curl_easy_perform() failed: Couldn't resolve host name Dec 26 13:43:47 volumio volumio-remote-updater[982]: terminate called after throwing an instance of 'std::invalid_argument' Dec 26 13:43:47 volumio volumio-remote-updater[982]: what(): parse error - unexpected end of input Dec 26 13:43:47 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Dec 26 13:43:47 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Dec 26 13:43:47 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Dec 26 13:43:47 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 26 13:43:47 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 26 13:43:47 volumio volumio-remote-updater[2317]: Volumio OTA Client: 1.7.0 Dec 26 13:43:47 volumio volumio-remote-updater[2317]: Error: No active session Dec 26 13:43:47 volumio volumio-remote-updater[2317]: [2025-12-26 12:43:47] [connect] Successful connection Dec 26 13:43:47 volumio volumio-remote-updater[2317]: [2025-12-26 12:43:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766753027 101 Dec 26 13:43:47 volumio volumio[1315]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Dec 26 13:43:48 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 26 13:43:48 volumio volumio[1315]: info: Loading i18n strings for locale de Dec 26 13:43:48 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 26 13:43:48 volumio volumio[1315]: info: Setting timezone to Europe/Berlin Dec 26 13:43:48 volumio sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 26 13:43:48 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:48 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:48 volumio sudo[2326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Dec 26 13:43:48 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:48 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:48 volumio sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 26 13:43:48 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:48 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:48 volumio sudo[2334]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Dec 26 13:43:48 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:48 volumio dbus-daemon[964]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2335 comm="/usr/bin/timedatectl set-timezone Europe/Berlin") Dec 26 13:43:48 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 26 13:43:48 volumio dbus-daemon[964]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 26 13:43:48 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 26 13:43:48 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:48 volumio volumio[1315]: Updating browse sources language Dec 26 13:43:48 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:43:49 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 13:43:49 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 26 13:43:49 volumio volumio[1315]: info: Loading i18n strings for locale de Dec 26 13:43:49 volumio volumio[1315]: Updating browse sources language Dec 26 13:43:49 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:43:49 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 26 13:43:49 volumio volumio[1315]: info: Setting timezone to Europe/Berlin Dec 26 13:43:49 volumio sudo[2339]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 26 13:43:49 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:49 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:49 volumio sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Dec 26 13:43:49 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:49 volumio sudo[2343]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:49 volumio sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 26 13:43:49 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:49 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:49 volumio sudo[2351]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Dec 26 13:43:49 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:49 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:49 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 26 13:43:49 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 26 13:43:49 volumio sudo[2354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 13:43:49 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:43:53 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Dec 26 13:43:53 volumio dnsmasq-dhcp[1992]: DHCPDISCOVER(wlan0) 5a:0e:3b:b2:fb:a7 Dec 26 13:43:53 volumio dnsmasq-dhcp[1992]: DHCPOFFER(wlan0) 192.168.211.117 5a:0e:3b:b2:fb:a7 Dec 26 13:43:53 volumio dnsmasq-dhcp[1992]: DHCPDISCOVER(wlan0) 5a:0e:3b:b2:fb:a7 Dec 26 13:43:53 volumio dnsmasq-dhcp[1992]: DHCPOFFER(wlan0) 192.168.211.117 5a:0e:3b:b2:fb:a7 Dec 26 13:43:54 volumio dnsmasq-dhcp[1992]: DHCPREQUEST(wlan0) 192.168.211.117 5a:0e:3b:b2:fb:a7 Dec 26 13:43:54 volumio dnsmasq-dhcp[1992]: DHCPACK(wlan0) 192.168.211.117 5a:0e:3b:b2:fb:a7 Dec 26 13:43:56 volumio volumio[1315]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Dec 26 13:43:56 volumio volumio[1315]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Dec 26 13:43:58 volumio volumio[1315]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 13:43:58 volumio volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 13:43:58 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:43:58 volumio volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:44:02 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 26 13:44:02 volumio volumio[1315]: info: System name has changed, restarting Shairport Sync Dec 26 13:44:02 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:02 volumio volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:02 volumio volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:44:02 volumio volumio[1315]: info: Discovery: Restarting Advertising due to device name change Dec 26 13:44:02 volumio sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 26 13:44:02 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:02 volumio volumio[1315]: info: Starting Shairport Sync Dec 26 13:44:02 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:02 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 26 13:44:02 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:02 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:44:02 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 13:44:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 13:44:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 13:44:02 volumio systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. Dec 26 13:44:02 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:02 volumio volumio[1315]: info: Permissions for /etc/hosts set Dec 26 13:44:02 volumio sudo[2389]: volumio : unable to resolve host volumio: System error Dec 26 13:44:02 volumio5 sudo[2389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio5 Dec 26 13:44:02 volumio5 volumio[1315]: info: Hostname now is volumio5 Dec 26 13:44:02 volumio5 sudo[2389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:02 volumio5 sudo[2389]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:02 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 26 13:44:02 volumio5 sudo[2392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 26 13:44:02 volumio5 sudo[2392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:02 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 13:44:02 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:02 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 13:44:02 volumio5 volumio[1315]: info: New hostname set Dec 26 13:44:02 volumio5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 13:44:02 volumio5 sudo[2384]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:02 volumio5 volumio[1315]: info: Shairport-Sync Started Dec 26 13:44:02 volumio5 sudo[2392]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:02 volumio5 volumio[1315]: info: Permissions for /etc/avahi/services/volumio.service Dec 26 13:44:02 volumio5 avahi-daemon[959]: Files changed, reloading. Dec 26 13:44:02 volumio5 volumio[1315]: info: Avahi name changed to volumio5 Dec 26 13:44:02 volumio5 avahi-daemon[959]: Loading service file /services/volumio.service. Dec 26 13:44:02 volumio5 systemd[1]: upmpdcli.service: Deactivated successfully. Dec 26 13:44:02 volumio5 systemd[1]: upmpdcli.service: Consumed 3.325s CPU time. Dec 26 13:44:02 volumio5 avahi-daemon[959]: Service "Volumio5" (/services/volumio.service) successfully established. Dec 26 13:44:03 volumio5 volumio[1315]: info: Discovery: A device disappeared from network Dec 26 13:44:03 volumio5 volumio[1315]: info: Discovery: Device volumio disappeared from network Dec 26 13:44:03 volumio5 volumio[1315]: info: Discovery: A device disappeared from network Dec 26 13:44:07 volumio5 volumio[1315]: info: Discovery: Started advertising with name: Volumio5 Dec 26 13:44:07 volumio5 volumio[1315]: info: Discovery: adding 49823c29-0646-4b74-8fab-a70a96946542 Dec 26 13:44:07 volumio5 volumio[1315]: info: Discovery: Found device Volumio5 Dec 26 13:44:07 volumio5 volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 26 13:44:07 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:07 volumio5 volumio[1315]: info: Discovery: this is already registered, 49823c29-0646-4b74-8fab-a70a96946542 Dec 26 13:44:07 volumio5 volumio[1315]: info: Discovery: Found device Volumio5 Dec 26 13:44:07 volumio5 volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 26 13:44:07 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:12 volumio5 sudo[2429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Dec 26 13:44:12 volumio5 sudo[2429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Dec 26 13:44:12 volumio5 sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2437]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:12 volumio5 sudo[2437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2435]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 13:44:12 volumio5 sudo[2435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2437]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 sudo[2435]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 sudo[2441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 13:44:12 volumio5 sudo[2441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2431]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 sudo[2429]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: info: Internal perms successfully set Dec 26 13:44:12 volumio5 volumio[1315]: info: smb.conf Permissions set Dec 26 13:44:12 volumio5 sudo[2444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Dec 26 13:44:12 volumio5 sudo[2444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Dec 26 13:44:12 volumio5 nmbd[1214]: [2025/12/26 13:44:12.147018, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 26 13:44:12 volumio5 nmbd[1214]: started asyncdns process 2446 Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 26 13:44:12 volumio5 volumio[1315]: info: Preparing to save Alsa Options, stopping services first Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 26 13:44:12 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::volumioPause Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreStateMachine::pause Dec 26 13:44:12 volumio5 volumio[1315]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-dacplus","label":"HiFiBerry DAC Plus"},"output_device":{"value":1,"label":"HiFiBerry DAC Plus"},"disallowPush":true} Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: Enabling I2S DAC: HiFiBerry DAC Plus Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 26 13:44:12 volumio5 sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: info: No Overlays Loaded Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 sudo[2452]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Dec 26 13:44:12 volumio5 sudo[2452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 volumio[1315]: warn: Unable to locate the device HiFiBerry DAC Plus a reboot may be required. Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a4000/status Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/status Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/#sound-dai-cells Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/compatible Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/reg Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/clocks Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/AVDD-supply Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/DVDD-supply Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/CPVDD-supply Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/pcm5122@4d/status Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/i2s-controller Dec 26 13:44:12 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Dec 26 13:44:12 volumio5 kernel: clk-hifiberry-dacpro dacpro_osc: Fail to register clock driver Dec 26 13:44:12 volumio5 kernel: clk-hifiberry-dacpro dacpro_osc: probe with driver clk-hifiberry-dacpro failed with error -17 Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Dec 26 13:44:12 volumio5 sudo[2452]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 kernel: pinctrl-rp1 1f000d0000.gpio: pin gpio18 already requested by 1f000a0000.i2s; cannot claim for 1f000a4000.i2s Dec 26 13:44:12 volumio5 kernel: pinctrl-rp1 1f000d0000.gpio: error -EINVAL: pin-18 (1f000a4000.i2s) Dec 26 13:44:12 volumio5 kernel: pinctrl-rp1 1f000d0000.gpio: error -EINVAL: could not request pin 18 (gpio18) from group gpio18 on device pinctrl-rp1 Dec 26 13:44:12 volumio5 kernel: designware-i2s 1f000a4000.i2s: Error applying setting, reverse things back Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 26 13:44:12 volumio5 volumio[1315]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 26 13:44:12 volumio5 volumio[1315]: info: Disabling external Volume Control Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 26 13:44:12 volumio5 volumio[1315]: info: Preparing to generate the ALSA configuration file Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 26 13:44:12 volumio5 sudo[2473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 13:44:12 volumio5 sudo[2473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 13:44:12 volumio5 sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 volumio[1315]: info: I2S Param [object Object] successfully enabled Dec 26 13:44:12 volumio5 sudo[2475]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 13:44:12 volumio5 volumio[1315]: info: Getting Alsa Cards List without I2S DAC Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 13:44:12 volumio5 volumio[1315]: info: VolumeController:: Volume=100 Mute =false Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreStateMachine::pushState Dec 26 13:44:12 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 26 13:44:12 volumio5 volumio[1315]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 26 13:44:12 volumio5 volumio[1315]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 26 13:44:12 volumio5 volumio[1315]: info: Cannot use regular scanning, forcing with ap-force Dec 26 13:44:12 volumio5 sudo[2495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 26 13:44:12 volumio5 sudo[2495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2495]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: command failed: Device or resource busy (-16) Dec 26 13:44:12 volumio5 volumio[1315]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 26 13:44:12 volumio5 volumio[1315]: command failed: Device or resource busy (-16) Dec 26 13:44:12 volumio5 volumio[1315]: info: Asound.conf file written Dec 26 13:44:12 volumio5 sudo[2499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 13:44:12 volumio5 sudo[2499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2499]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 26 13:44:12 volumio5 volumio[1315]: No state is present for card vc4hdmi0 Dec 26 13:44:12 volumio5 volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 26 13:44:12 volumio5 volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 26 13:44:12 volumio5 volumio[1315]: Hardware is initialized using a generic method Dec 26 13:44:12 volumio5 volumio[1315]: No state is present for card vc4hdmi0 Dec 26 13:44:12 volumio5 volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 26 13:44:12 volumio5 volumio[1315]: No state is present for card vc4hdmi1 Dec 26 13:44:12 volumio5 volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 26 13:44:12 volumio5 volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 26 13:44:12 volumio5 volumio[1315]: Hardware is initialized using a generic method Dec 26 13:44:12 volumio5 volumio[1315]: No state is present for card vc4hdmi1 Dec 26 13:44:12 volumio5 volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 26 13:44:12 volumio5 volumio[1315]: No state is present for card BossDAC Dec 26 13:44:12 volumio5 volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 26 13:44:12 volumio5 volumio[1315]: Found hardware: "BossDAC" "" "" "" "" Dec 26 13:44:12 volumio5 volumio[1315]: Hardware is initialized using a generic method Dec 26 13:44:12 volumio5 volumio[1315]: No state is present for card BossDAC Dec 26 13:44:12 volumio5 volumio[1315]: info: Output device has changed, restarting MPD Dec 26 13:44:12 volumio5 volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:44:12 volumio5 sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:44:12 volumio5 sudo[2505]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:44:12 volumio5 sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 volumio[1315]: info: Output device has changed, restarting MPD Dec 26 13:44:12 volumio5 volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 sudo[2515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:44:12 volumio5 sudo[2515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2515]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 13:44:12 volumio5 volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:44:12 volumio5 sudo[2517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:44:12 volumio5 sudo[2517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 volumio[1315]: info: MPD Permissions set Dec 26 13:44:12 volumio5 volumio[1315]: info: MPD Permissions set Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:12 volumio5 volumio[1315]: info: Starting Shairport Sync Dec 26 13:44:12 volumio5 volumio[1315]: info: Starting Shairport Sync Dec 26 13:44:12 volumio5 sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:44:12 volumio5 sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 sudo[2539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:44:12 volumio5 sudo[2539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:12 volumio5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 13:44:12 volumio5 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 13:44:12 volumio5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 13:44:12 volumio5 systemd[1]: shairport-sync.service: Consumed 1.519s CPU time. Dec 26 13:44:12 volumio5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 13:44:12 volumio5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 13:44:12 volumio5 sudo[2537]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 13:44:12 volumio5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 13:44:12 volumio5 volumio[1315]: info: Shairport-Sync Started Dec 26 13:44:12 volumio5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 13:44:12 volumio5 sudo[2539]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:12 volumio5 volumio[1315]: info: Shairport-Sync Started Dec 26 13:44:12 volumio5 systemd[1]: mpd.service: Deactivated successfully. Dec 26 13:44:12 volumio5 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 13:44:12 volumio5 systemd[1]: mpd.service: Consumed 1.263s CPU time. Dec 26 13:44:12 volumio5 systemd[1]: mpd.socket: Deactivated successfully. Dec 26 13:44:12 volumio5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 13:44:12 volumio5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 13:44:12 volumio5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 13:44:12 volumio5 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 13:44:12 volumio5 sudo[2559]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:44:12 volumio5 sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 13:44:12 volumio5 sudo[2559]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:13 volumio5 mpd[2561]: 2025-12-26T13:44:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 13:44:13 volumio5 systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 13:44:13 volumio5 sudo[2517]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:13 volumio5 sudo[2507]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:13 volumio5 volumio[1315]: error: MPD error: The expression evaluated to a falsy value: Dec 26 13:44:13 volumio5 volumio[1315]: assert.ok(self.idling) Dec 26 13:44:13 volumio5 volumio[1315]: error: The expression evaluated to a falsy value: Dec 26 13:44:13 volumio5 volumio[1315]: assert.ok(self.idling) Dec 26 13:44:13 volumio5 volumio[1315]: error: updateQueue error: null Dec 26 13:44:16 volumio5 sudo[2473]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:16 volumio5 sudo[2568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 13:44:16 volumio5 sudo[2568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:16 volumio5 sudo[2570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 13:44:16 volumio5 sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:16 volumio5 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 13:44:16 volumio5 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 13:44:16 volumio5 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 13:44:16 volumio5 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 13:44:16 volumio5 sudo[2568]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:16 volumio5 mpd_monitor.sh[2573]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 13:44:16 volumio5 sudo[2570]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:16 volumio5 volumio[1315]: info: Successfully started MPD Monitor Dec 26 13:44:16 volumio5 volumio[1315]: info: Successfully started MPD Monitor Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreStateMachine::getcurrentVolume Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:44:16 volumio5 volumio[1315]: info: VolumeController:: Volume=100 Mute =false Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreStateMachine::pushState Dec 26 13:44:16 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreStateMachine::updateTrackBlock Dec 26 13:44:16 volumio5 volumio[1315]: info: CorePlayQueue::getTrackBlock Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:44:16 volumio5 volumio[1315]: info: VolumeController:: Volume=100 Mute =false Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreStateMachine::pushState Dec 26 13:44:16 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:16 volumio5 volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 26 13:44:17 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:17 volumio5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 26 13:44:17 volumio5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 13:44:17 volumio5 systemd[1]: upmpdcli.service: Consumed 3.325s CPU time. Dec 26 13:44:17 volumio5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 13:44:17 volumio5 sudo[2441]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:17 volumio5 volumio[1315]: info: Upmpdcli Daemon Started Dec 26 13:44:17 volumio5 upmpdcli[2611]: writing RSA key Dec 26 13:44:18 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 26 13:44:18 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 13:44:19 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Dec 26 13:44:19 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Dec 26 13:44:19 volumio5 sudo[2616]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 13:44:19 volumio5 sudo[2616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:19 volumio5 sudo[2616]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:19 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 26 13:44:19 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 13:44:19 volumio5 systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 26 13:44:20 volumio5 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:44:21 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 26 13:44:21 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 26 13:44:21 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 26 13:44:21 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 26 13:44:21 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::Reload Ui Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 26 13:44:22 volumio5 volumio[1315]: info: Wizard terminated Successfully Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:44:22 volumio5 volumio[1315]: info: Player Reboot required after I2S DAC has been enabled in wizard Dec 26 13:44:22 volumio5 volumio[1315]: info: Executing Deferred Wifi Connection Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 26 13:44:22 volumio5 volumio[1315]: info: Saving new wireless network Dec 26 13:44:22 volumio5 sudo[2621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 26 13:44:22 volumio5 sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:22 volumio5 sudo[2621]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:22 volumio5 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 26 13:44:22 volumio5 volumio[1315]: info: Discovery: Restarting Advertising due to device name change Dec 26 13:44:22 volumio5 sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 26 13:44:22 volumio5 sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:22 volumio5 systemd[1]: Stopping wireless.service - Wireless Services... Dec 26 13:44:22 volumio5 systemd[1]: wireless.service: Deactivated successfully. Dec 26 13:44:22 volumio5 systemd[1]: Stopped wireless.service - Wireless Services. Dec 26 13:44:22 volumio5 systemd[1]: Starting wireless.service - Wireless Services... Dec 26 13:44:23 volumio5 wireless.js[2629]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 26 13:44:23 volumio5 wireless.js[2629]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 26 13:44:23 volumio5 wireless.js[2629]: WIRELESS.JS: Cleaning previous... Dec 26 13:44:23 volumio5 systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 26 13:44:23 volumio5 dnsmasq[1992]: exiting on receipt of SIGTERM Dec 26 13:44:23 volumio5 systemd[1]: dnsmasq.service: Deactivated successfully. Dec 26 13:44:23 volumio5 systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 26 13:44:23 volumio5 systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 26 13:44:23 volumio5 dhcpcd[1021]: wlan0: carrier lost Dec 26 13:44:23 volumio5 systemd[1]: hostapd.service: Deactivated successfully. Dec 26 13:44:23 volumio5 systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 26 13:44:23 volumio5 sudo[2653]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 26 13:44:23 volumio5 sudo[2653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 13:44:23 volumio5 avahi-daemon[959]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 26 13:44:23 volumio5 avahi-daemon[959]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 26 13:44:23 volumio5 avahi-daemon[959]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 26 13:44:23 volumio5 volumio[1315]: info: Discovery: A device disappeared from network Dec 26 13:44:23 volumio5 sudo[2653]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:23 volumio5 sudo[2665]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 26 13:44:23 volumio5 sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 13:44:23 volumio5 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 26 13:44:23 volumio5 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 26 13:44:23 volumio5 systemd[1]: welcome.service: Deactivated successfully. Dec 26 13:44:23 volumio5 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 26 13:44:23 volumio5 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 26 13:44:23 volumio5 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 26 13:44:23 volumio5 volumio[1315]: info: Discovery: A device disappeared from network Dec 26 13:44:24 volumio5 sudo[2665]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:24 volumio5 welcome[2667]: Resolved ip:[0] Dec 26 13:44:24 volumio5 sudo[2670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:24 volumio5 sudo[2670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:24 volumio5 sudo[2670]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:24 volumio5 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 26 13:44:24 volumio5 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 26 13:44:24 volumio5 sudo[2444]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:24 volumio5 volumio[1315]: error: Cannot restart SAMBA Dec 26 13:44:24 volumio5 wireless.js[2629]: WIRELESS.JS: Stopped aP Dec 26 13:44:24 volumio5 sudo[2681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 26 13:44:24 volumio5 sudo[2681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:24 volumio5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 26 13:44:24 volumio5 sudo[2681]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:24 volumio5 sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 26 13:44:24 volumio5 sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:24 volumio5 sudo[2683]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:24 volumio5 sudo[2691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 26 13:44:24 volumio5 sudo[2691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:24 volumio5 sudo[2691]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:24 volumio5 sudo[2693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 26 13:44:24 volumio5 sudo[2693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:24 volumio5 sudo[2698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:24 volumio5 sudo[2698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:24 volumio5 sudo[2698]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:25 volumio5 ntpd[1099]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=150 secs Dec 26 13:44:25 volumio5 sudo[2701]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:25 volumio5 sudo[2701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:25 volumio5 sudo[2701]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:26 volumio5 sudo[2693]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:26 volumio5 wireless.js[2629]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Dec 26 13:44:26 volumio5 sudo[2719]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 26 13:44:26 volumio5 sudo[2719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:26 volumio5 sudo[2719]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:26 volumio5 sudo[2721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Dec 26 13:44:26 volumio5 sudo[2721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:26 volumio5 sudo[2721]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:26 volumio5 wireless.js[2629]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Dec 26 13:44:26 volumio5 wireless.js[2629]: WIRELESS.JS: Start wireless flow Dec 26 13:44:26 volumio5 wireless.js[2629]: WIRELESS.JS: Stopped hotspot (if there).. Dec 26 13:44:26 volumio5 sudo[2729]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 26 13:44:26 volumio5 sudo[2729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 13:44:26 volumio5 sudo[2729]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:26 volumio5 sudo[2731]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 26 13:44:26 volumio5 sudo[2731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 13:44:27 volumio5 sudo[2731]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:27 volumio5 sudo[2734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:27 volumio5 sudo[2734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:27 volumio5 sudo[2734]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:27 volumio5 wireless.js[2629]: WIRELESS.JS: DHCP IP fallback Dec 26 13:44:27 volumio5 wireless.js[2629]: WIRELESS.JS: Start ap Dec 26 13:44:27 volumio5 wpa_supplicant[2739]: Successfully initialized wpa_supplicant Dec 26 13:44:27 volumio5 sudo[2740]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 26 13:44:27 volumio5 sudo[2740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 13:44:27 volumio5 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 26 13:44:27 volumio5 sudo[2740]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:27 volumio5 dhcpcd[1021]: control_free: No such file or directory Dec 26 13:44:27 volumio5 dhcpcd[1021]: control command: /sbin/dhcpcd Dec 26 13:44:27 volumio5 dhcpcd[1021]: lo: IAID 00:00:00:00 Dec 26 13:44:27 volumio5 dhcpcd[1021]: lo: adding address fe80::d476:ae5:7a1c:3c2c Dec 26 13:44:27 volumio5 dhcpcd[1021]: ipv6_addaddr1: Permission denied Dec 26 13:44:27 volumio5 dhcpcd[1021]: lo: using static address 127.0.0.1/8 Dec 26 13:44:27 volumio5 dhcpcd[1021]: lo: adding route to 127.0.0.0/8 Dec 26 13:44:27 volumio5 systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Dec 26 13:44:27 volumio5 wpa_supplicant[2739]: nl80211: kernel reports: Registration to specific type not supported Dec 26 13:44:27 volumio5 volumio[1315]: info: Discovery: Started advertising with name: Volumio5 Dec 26 13:44:27 volumio5 sudo[2773]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:27 volumio5 sudo[2773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:27 volumio5 sudo[2773]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:28 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:28 volumio5 sudo[2777]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:28 volumio5 sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:28 volumio5 sudo[2777]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:28 volumio5 volumio[1315]: info: Discovery: adding 49823c29-0646-4b74-8fab-a70a96946542 Dec 26 13:44:28 volumio5 volumio[1315]: info: Discovery: Found device Volumio5 Dec 26 13:44:28 volumio5 volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 26 13:44:28 volumio5 volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 26 13:44:28 volumio5 sudo[2781]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:28 volumio5 sudo[2781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:28 volumio5 sudo[2781]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:29 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:29 volumio5 sudo[2785]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:29 volumio5 sudo[2785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:29 volumio5 sudo[2785]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:29 volumio5 sudo[2788]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:29 volumio5 sudo[2788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:29 volumio5 sudo[2788]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:29 volumio5 wpa_supplicant[2770]: wlan0: Trying to associate with 62:b5:8d:a6:b1:80 (SSID='FRITZ!Box 6490 Cable' freq=5500 MHz) Dec 26 13:44:30 volumio5 wpa_supplicant[2770]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 26 13:44:30 volumio5 wpa_supplicant[2770]: BSSID 62:b5:8d:a6:b1:80 ignore list count incremented to 2, ignoring for 10 seconds Dec 26 13:44:30 volumio5 wpa_supplicant[2770]: wlan0: Trying to associate with 60:b5:8d:a6:b1:80 (SSID='FRITZ!Box 6490 Cable' freq=2412 MHz) Dec 26 13:44:30 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:30 volumio5 sudo[2792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:30 volumio5 sudo[2792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:30 volumio5 sudo[2792]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:30 volumio5 sudo[2795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:30 volumio5 sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:30 volumio5 sudo[2795]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:31 volumio5 wpa_supplicant[2770]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 26 13:44:31 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:31 volumio5 sudo[2799]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:31 volumio5 sudo[2799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:31 volumio5 sudo[2799]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:31 volumio5 sudo[2802]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:31 volumio5 sudo[2802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:31 volumio5 sudo[2802]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:32 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:32 volumio5 sudo[2806]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:32 volumio5 sudo[2806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:32 volumio5 sudo[2806]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:32 volumio5 sudo[2809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:32 volumio5 sudo[2809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:32 volumio5 sudo[2809]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:33 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:33 volumio5 sudo[2813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:33 volumio5 sudo[2813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:33 volumio5 sudo[2813]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:33 volumio5 wpa_supplicant[2770]: wlan0: Trying to associate with 62:b5:8d:a6:b1:80 (SSID='FRITZ!Box 6490 Cable' freq=5500 MHz) Dec 26 13:44:33 volumio5 sudo[2816]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:33 volumio5 sudo[2816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:33 volumio5 sudo[2816]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:33 volumio5 wpa_supplicant[2770]: wlan0: Associated with 62:b5:8d:a6:b1:80 Dec 26 13:44:33 volumio5 wpa_supplicant[2770]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 26 13:44:33 volumio5 wpa_supplicant[2770]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 26 13:44:33 volumio5 wpa_supplicant[2770]: wlan0: WPA: Key negotiation completed with 62:b5:8d:a6:b1:80 [PTK=CCMP GTK=CCMP] Dec 26 13:44:33 volumio5 wpa_supplicant[2770]: wlan0: CTRL-EVENT-CONNECTED - Connection to 62:b5:8d:a6:b1:80 completed [id=0 id_str=] Dec 26 13:44:33 volumio5 dhcpcd[1021]: wlan0: carrier acquired Dec 26 13:44:33 volumio5 dhcpcd[1021]: wlan0: connected to Access Point: FRITZ!Box 6490 Cable Dec 26 13:44:33 volumio5 dhcpcd[1021]: wlan0: IAID 67:7b:81:18 Dec 26 13:44:34 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:34 volumio5 sudo[2821]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:34 volumio5 sudo[2821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:34 volumio5 sudo[2821]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:34 volumio5 dhcpcd[1021]: wlan0: soliciting an IPv6 router Dec 26 13:44:34 volumio5 sudo[2824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:34 volumio5 sudo[2824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:34 volumio5 sudo[2824]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:35 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:35 volumio5 sudo[2828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:35 volumio5 sudo[2828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:35 volumio5 sudo[2828]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:35 volumio5 dhcpcd[1021]: wlan0: soliciting a DHCP lease Dec 26 13:44:35 volumio5 dhcpcd[1021]: wlan0: offered 192.168.178.59 from 192.168.178.1 Dec 26 13:44:35 volumio5 dhcpcd[1021]: wlan0: probing address 192.168.178.59/24 Dec 26 13:44:35 volumio5 sudo[2833]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:35 volumio5 sudo[2833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:35 volumio5 sudo[2833]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:36 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:36 volumio5 sudo[2840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:36 volumio5 sudo[2840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:36 volumio5 sudo[2840]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:36 volumio5 sudo[2857]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:36 volumio5 sudo[2857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:36 volumio5 sudo[2857]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:37 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:37 volumio5 sudo[2861]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:37 volumio5 sudo[2861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:37 volumio5 sudo[2861]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:37 volumio5 sudo[2864]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:37 volumio5 sudo[2864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:37 volumio5 sudo[2864]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:38 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:38 volumio5 sudo[2868]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:38 volumio5 sudo[2868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:38 volumio5 sudo[2868]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:38 volumio5 sudo[2871]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:38 volumio5 sudo[2871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:38 volumio5 sudo[2871]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:39 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:39 volumio5 sudo[2875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:39 volumio5 sudo[2875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:39 volumio5 sudo[2875]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:39 volumio5 sudo[2878]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:44:39 volumio5 sudo[2878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:39 volumio5 sudo[2878]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:40 volumio5 wireless.js[2629]: WIRELESS.JS: trying... Dec 26 13:44:40 volumio5 sudo[2882]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 26 13:44:40 volumio5 sudo[2882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 13:44:40 volumio5 sudo[2882]: pam_unix(sudo:session): session closed for user root Dec 26 13:44:40 volumio5 dhcpcd[1021]: wlan0: leased 192.168.178.59 for 864000 seconds Dec 26 13:44:40 volumio5 avahi-daemon[959]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.59. Dec 26 13:44:40 volumio5 avahi-daemon[959]: New relevant interface wlan0.IPv4 for mDNS. Dec 26 13:44:40 volumio5 avahi-daemon[959]: Registering new address record for 192.168.178.59 on wlan0.IPv4. Dec 26 13:44:40 volumio5 dhcpcd[1021]: wlan0: adding route to 192.168.178.0/24 Dec 26 13:44:40 volumio5 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 26 13:44:40 volumio5 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 26 13:44:40 volumio5 systemd[1]: welcome.service: Deactivated successfully. Dec 26 13:44:40 volumio5 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 26 13:44:40 volumio5 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 26 13:44:40 volumio5 dhcpcd[1021]: wlan0: adding default route via 192.168.178.1 Dec 26 13:44:40 volumio5 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 26 13:44:40 volumio5 welcome[2896]: Resolved ip:[1] 192.168.178.59 Dec 26 13:44:40 volumio5 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 26 13:44:40 volumio5 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 26 13:44:40 volumio5 volumio[1315]: compat.c: read() failed: Resource temporarily unavailable Dec 26 13:44:40 volumio5 volumio[1315]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:44:40 volumio5 volumio[1315]: Error: dns service error: unknown Dec 26 13:44:40 volumio5 volumio[1315]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 26 13:44:40 volumio5 volumio[1315]: errorCode: -65537 Dec 26 13:44:40 volumio5 volumio[1315]: } Dec 26 13:44:40 volumio5 volumio[1315]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:44:40 volumio5 sudo[2925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 13:43' Dec 26 13:44:40 volumio5 sudo[2925]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"