-- Logs begin at Sat 2025-03-01 10:48:15 CET, end at Sat 2025-03-01 10:50:37 CET. -- Mar 01 10:49:01 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 01 10:49:24 volumio ntpd[955]: Soliciting pool server 192.86.14.67 Mar 01 10:49:24 volumio ntpd[955]: Soliciting pool server 178.215.228.24 Mar 01 10:49:25 volumio ntpd[955]: Soliciting pool server 188.241.28.254 Mar 01 10:49:25 volumio ntpd[955]: Soliciting pool server 85.115.212.251 Mar 01 10:49:25 volumio ntpd[955]: Soliciting pool server 193.25.222.136 Mar 01 10:49:25 volumio ntpd[955]: Soliciting pool server 193.59.134.156 Mar 01 10:49:26 volumio ntpd[955]: Soliciting pool server 162.159.200.1 Mar 01 10:49:26 volumio ntpd[955]: Soliciting pool server 185.157.13.96 Mar 01 10:49:26 volumio ntpd[955]: Soliciting pool server 89.161.47.136 Mar 01 10:49:26 volumio ntpd[955]: Soliciting pool server 89.161.47.136 Mar 01 10:49:27 volumio ntpd[955]: Soliciting pool server 162.159.200.123 Mar 01 10:49:27 volumio ntpd[955]: Soliciting pool server 51.68.141.5 Mar 01 10:49:27 volumio ntpd[955]: Soliciting pool server 91.212.242.21 Mar 01 10:49:28 volumio ntpd[955]: Soliciting pool server 89.25.168.148 Mar 01 10:49:28 volumio ntpd[955]: Soliciting pool server 91.210.128.220 Mar 01 10:49:29 volumio ntpd[955]: Soliciting pool server 2a03:7580:1:217::10 Mar 01 10:49:33 volumio ntpd[955]: receive: Unexpected origin timestamp 0xeb6d56ac.21a2eaa3 does not match aorg 0000000000.00000000 from server@51.68.141.5 xmt 0xeb6d56ad.31d172e2 Mar 01 10:49:33 volumio ntpd[955]: receive: Unexpected origin timestamp 0xeb6d56ac.21a12139 does not match aorg 0000000000.00000000 from server@91.212.242.21 xmt 0xeb6d56ad.3231333f Mar 01 10:49:33 volumio ntpd[955]: receive: Unexpected origin timestamp 0xeb6d56ac.21a4f714 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeb6d56ad.3497ed7c Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 01 10:49:48 volumio volumio[1060]: info: Preparing to save Alsa Options, stopping services first Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:49:48 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::volumioPause Mar 01 10:49:48 volumio volumio[1060]: info: CoreStateMachine::pause Mar 01 10:49:48 volumio volumio[1060]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"innomaker-dac-pro","label":"Innomaker Dac Pro"},"output_device":{"value":1,"label":"Innomaker Dac Pro"},"disallowPush":true} Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: Enabling I2S DAC: Innomaker Dac Pro Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 01 10:49:48 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: info: No Overlays Loaded Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-katana-dac-audio Mar 01 10:49:48 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a4000/#sound-dai-cells Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a4000/status Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/#address-cells Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/#size-cells Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/status Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/clock-frequency Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/label Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/dais Mar 01 10:49:48 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Mar 01 10:49:48 volumio volumio[1060]: warn: Unable to locate the device Innomaker Dac Pro a reboot may be required. Mar 01 10:49:48 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: Found match in i2s Card Database: setting mixer Master for card Innomaker Dac Pro Mar 01 10:49:48 volumio kernel: allo-katana-codec 1-0030: Failed to read Chip or wrong Chip id: -121 Mar 01 10:49:48 volumio kernel: allo-katana-codec: probe of 1-0030 failed with error -121 Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 10:49:48 volumio volumio[1060]: info: Updating Volume Controller Parameters: Device: 2 Name: Innomaker Dac Pro Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 01 10:49:48 volumio volumio[1060]: info: Disabling external Volume Control Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 01 10:49:48 volumio volumio[1060]: info: Preparing to generate the ALSA configuration file Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 01 10:49:48 volumio sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 01 10:49:48 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio volumio[1060]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 01 10:49:48 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 01 10:49:48 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio volumio[1060]: info: I2S Param [object Object] successfully enabled Mar 01 10:49:48 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: info: VolumeController:: Volume=undefined Mute =false Mar 01 10:49:48 volumio volumio[1060]: info: CoreStateMachine::pushState Mar 01 10:49:48 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Mar 01 10:49:48 volumio volumio[1060]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 01 10:49:48 volumio volumio[1060]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 01 10:49:48 volumio volumio[1060]: info: Cannot use regular scanning, forcing with ap-force Mar 01 10:49:48 volumio sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 01 10:49:48 volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1620]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: command failed: Device or resource busy (-16) Mar 01 10:49:48 volumio volumio[1060]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 01 10:49:48 volumio volumio[1060]: command failed: Device or resource busy (-16) Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:49:48 volumio volumio[1060]: info: Getting Alsa Cards List without I2S DAC Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: Invalid card number. Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 10:49:48 volumio volumio[1060]: info: Asound.conf file written Mar 01 10:49:48 volumio sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 01 10:49:48 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: info: Output device has changed, restarting MPD Mar 01 10:49:48 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 10:49:48 volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 10:49:48 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio systemd[1]: Stopping Music Player Daemon... Mar 01 10:49:48 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 10:49:48 volumio systemd[1]: mpd.service: Succeeded. Mar 01 10:49:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 01 10:49:48 volumio volumio[1060]: info: Output device has changed, restarting MPD Mar 01 10:49:48 volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 10:49:48 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 10:49:48 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio systemd[1]: Starting Music Player Daemon... Mar 01 10:49:48 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 10:49:48 volumio volumio[1060]: info: MPD Permissions set Mar 01 10:49:48 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:48 volumio systemd[1]: mpd.service: Succeeded. Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 01 10:49:48 volumio systemd[1]: Starting Music Player Daemon... Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:48 volumio volumio[1060]: info: Starting Shairport Sync Mar 01 10:49:48 volumio volumio[1060]: info: Starting Shairport Sync Mar 01 10:49:48 volumio sudo[1655]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 10:49:48 volumio sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 10:49:48 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 10:49:48 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 10:49:48 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 01 10:49:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 10:49:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 10:49:48 volumio sudo[1664]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: info: Shairport-Sync Started Mar 01 10:49:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 10:49:48 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 01 10:49:48 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 01 10:49:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 10:49:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 10:49:48 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: info: Shairport-Sync Started Mar 01 10:49:48 volumio mpd[1669]: Mar 01 10:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 01 10:49:48 volumio systemd[1]: Started Music Player Daemon. Mar 01 10:49:48 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:48 volumio volumio[1060]: error: MPD error: The expression evaluated to a falsy value: Mar 01 10:49:48 volumio volumio[1060]: assert.ok(self.idling) Mar 01 10:49:48 volumio volumio[1060]: error: The expression evaluated to a falsy value: Mar 01 10:49:48 volumio volumio[1060]: assert.ok(self.idling) Mar 01 10:49:48 volumio volumio[1060]: error: updateQueue error: null Mar 01 10:49:52 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:52 volumio volumio[1060]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 01 10:49:52 volumio volumio[1060]: info: CoreStateMachine::getcurrentVolume Mar 01 10:49:52 volumio volumio[1060]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 10:49:52 volumio volumio[1060]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 01 10:49:52 volumio volumio[1060]: info: VolumeController:: Volume=undefined Mute =false Mar 01 10:49:52 volumio volumio[1060]: info: CoreStateMachine::pushState Mar 01 10:49:52 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:49:52 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Mar 01 10:49:52 volumio volumio[1060]: info: CoreStateMachine::updateTrackBlock Mar 01 10:49:52 volumio volumio[1060]: info: CorePlayQueue::getTrackBlock Mar 01 10:49:52 volumio volumio[1060]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 10:49:52 volumio volumio[1060]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 01 10:49:52 volumio volumio[1060]: info: VolumeController:: Volume=undefined Mute =false Mar 01 10:49:52 volumio volumio[1060]: info: CoreStateMachine::pushState Mar 01 10:49:52 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:49:52 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Mar 01 10:49:53 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:49:55 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:49:55 volumio volumio[1060]: info: Retrieving Cloud Streaming UI Mar 01 10:49:56 volumio volumio[1060]: info: Getting Tidal Cloud Configuration Mar 01 10:49:56 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:49:56 volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Mar 01 10:49:56 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:49:56 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:49:56 volumio volumio[1060]: info: Getting Spotify Cloud Configuration Mar 01 10:49:56 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:49:56 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:49:56 volumio volumio[1060]: info: Saving Spotify Acccount Mar 01 10:49:56 volumio volumio[1060]: info: Got Tidal Cloud Configuration Mar 01 10:49:56 volumio volumio[1060]: info: Got it Mar 01 10:49:56 volumio volumio[1060]: info: Got it Mar 01 10:49:56 volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 01 10:49:56 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:49:56 volumio volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 01 10:49:58 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 10:49:58 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:58 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:58 volumio sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 10:49:58 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:49:58 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root Mar 01 10:49:58 volumio volumio[1060]: verbose: New Socket.io Connection to 192.168.18.227 from 192.168.18.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 01 10:49:58 volumio volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 10:49:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 10:49:58 volumio volumio[1060]: info: Received Get System Info Mar 01 10:49:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:49:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:49:58 volumio volumio[1060]: info: Discovery: Getting this device information Mar 01 10:49:58 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:49:58 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:49:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:50:00 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 10:50:00 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:00 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:00 volumio sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 10:50:00 volumio sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:00 volumio sudo[1707]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:00 volumio volumio[1060]: verbose: New Socket.io Connection to 192.168.18.227 from 192.168.18.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 01 10:50:00 volumio volumio[1060]: info: Received Get System Info Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:50:00 volumio volumio[1060]: info: Discovery: Getting this device information Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:00 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:50:00 volumio volumio-remote-updater[690]: No test mode Mar 01 10:50:00 volumio volumio-remote-updater[690]: No alpha test mode Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:00 volumio volumio[1060]: info: Retrieving Cloud Streaming UI Mar 01 10:50:00 volumio volumio[1060]: info: Getting Tidal Cloud Configuration Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:00 volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Mar 01 10:50:00 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:00 volumio volumio[1060]: info: Getting Spotify Cloud Configuration Mar 01 10:50:00 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:00 volumio volumio[1060]: info: Saving Spotify Acccount Mar 01 10:50:00 volumio volumio[1060]: info: Got Tidal Cloud Configuration Mar 01 10:50:00 volumio volumio[1060]: info: Got it Mar 01 10:50:00 volumio volumio[1060]: info: Got it Mar 01 10:50:00 volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:00 volumio volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 01 10:50:00 volumio volumio[1060]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 01 10:50:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 01 10:50:01 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 01 10:50:01 volumio volumio[1060]: info: Loading i18n strings for locale pl Mar 01 10:50:01 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 01 10:50:01 volumio volumio[1060]: info: Setting timezone to Europe/Warsaw Mar 01 10:50:01 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 01 10:50:01 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:01 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:01 volumio sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Mar 01 10:50:01 volumio sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:01 volumio sudo[1718]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:01 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 01 10:50:01 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:01 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:01 volumio sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Mar 01 10:50:01 volumio sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:01 volumio dbus-daemon[698]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=1725 comm="/usr/bin/timedatectl set-timezone Europe/Warsaw ") Mar 01 10:50:02 volumio systemd[1]: Starting Time & Date Service... Mar 01 10:50:02 volumio dbus-daemon[698]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 01 10:50:02 volumio systemd[1]: Started Time & Date Service. Mar 01 10:50:02 volumio sudo[1724]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:02 volumio volumio[1060]: Updating browse sources language Mar 01 10:50:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 10:50:02 volumio volumio[1060]: info: Fetching Streaming Services browse cache Mar 01 10:50:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 01 10:50:03 volumio volumio[1060]: info: Preparing to save Alsa Options, stopping services first Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:03 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::volumioPause Mar 01 10:50:03 volumio volumio[1060]: info: CoreStateMachine::pause Mar 01 10:50:03 volumio volumio[1060]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"Innomaker Dac Pro"},"output_device":{"value":1,"label":"Innomaker Dac Pro"},"disallowPush":true} Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: warn: Unable to locate the device Innomaker Dac Pro a reboot may be required. Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: Found match in i2s Card Database: setting mixer Master for card Innomaker Dac Pro Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 10:50:03 volumio volumio[1060]: info: Updating Volume Controller Parameters: Device: 2 Name: Innomaker Dac Pro Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 01 10:50:03 volumio volumio[1060]: info: Disabling external Volume Control Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:03 volumio volumio[1060]: info: Preparing to generate the ALSA configuration file Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 01 10:50:03 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 01 10:50:03 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio volumio[1060]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 01 10:50:03 volumio sudo[1754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 01 10:50:03 volumio sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio sudo[1754]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:03 volumio volumio[1060]: info: VolumeController:: Volume=undefined Mute =false Mar 01 10:50:03 volumio volumio[1060]: info: CoreStateMachine::pushState Mar 01 10:50:03 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Mar 01 10:50:03 volumio volumio[1060]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 01 10:50:03 volumio volumio[1060]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 01 10:50:03 volumio volumio[1060]: info: Cannot use regular scanning, forcing with ap-force Mar 01 10:50:03 volumio sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 01 10:50:03 volumio sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio sudo[1758]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:03 volumio volumio[1060]: command failed: Device or resource busy (-16) Mar 01 10:50:03 volumio volumio[1060]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 01 10:50:03 volumio volumio[1060]: command failed: Device or resource busy (-16) Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 10:50:03 volumio volumio[1060]: info: Getting Alsa Cards List without I2S DAC Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: Invalid card number. Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 10:50:03 volumio volumio[1060]: info: Asound.conf file unchanged, so no further update is needed Mar 01 10:50:03 volumio volumio[1060]: info: Output device has changed, restarting MPD Mar 01 10:50:03 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 10:50:03 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:03 volumio sudo[1772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 10:50:03 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio systemd[1]: Stopping Music Player Daemon... Mar 01 10:50:03 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 10:50:03 volumio systemd[1]: mpd.service: Succeeded. Mar 01 10:50:03 volumio systemd[1]: Stopped Music Player Daemon. Mar 01 10:50:03 volumio volumio[1060]: info: MPD Permissions set Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:03 volumio volumio[1060]: info: Starting Shairport Sync Mar 01 10:50:03 volumio systemd[1]: Starting Music Player Daemon... Mar 01 10:50:03 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 10:50:03 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 10:50:03 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:03 volumio volumio[1060]: info: Retrieving Cloud Streaming UI Mar 01 10:50:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 10:50:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 10:50:03 volumio volumio[1060]: info: Getting Tidal Cloud Configuration Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:03 volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Mar 01 10:50:03 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:03 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:03 volumio volumio[1060]: info: Getting Spotify Cloud Configuration Mar 01 10:50:03 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:03 volumio volumio[1060]: info: Saving Spotify Acccount Mar 01 10:50:03 volumio volumio[1060]: info: Got Tidal Cloud Configuration Mar 01 10:50:03 volumio sudo[1777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 10:50:03 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:03 volumio volumio[1060]: info: Got it Mar 01 10:50:03 volumio volumio[1060]: info: Got it Mar 01 10:50:03 volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 01 10:50:03 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:03 volumio volumio[1060]: info: Shairport-Sync Started Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:03 volumio volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 01 10:50:03 volumio mpd[1787]: Mar 01 10:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 01 10:50:03 volumio systemd[1]: Started Music Player Daemon. Mar 01 10:50:03 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:03 volumio volumio[1060]: error: updateQueue error: null Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Mar 01 10:50:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 01 10:50:06 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:06 volumio volumio[1060]: info: Retrieving Cloud Streaming UI Mar 01 10:50:06 volumio volumio[1060]: info: Getting Tidal Cloud Configuration Mar 01 10:50:06 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:06 volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Mar 01 10:50:06 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:50:06 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:06 volumio volumio[1060]: info: Getting Spotify Cloud Configuration Mar 01 10:50:06 volumio volumio[1060]: info: Asking plugin for UI Config Mar 01 10:50:06 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:06 volumio volumio[1060]: info: Saving Spotify Acccount Mar 01 10:50:06 volumio volumio[1060]: info: Got Tidal Cloud Configuration Mar 01 10:50:06 volumio volumio[1060]: info: Got it Mar 01 10:50:06 volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 01 10:50:06 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 10:50:06 volumio volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 01 10:50:07 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:07 volumio volumio[1060]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 01 10:50:07 volumio volumio[1060]: info: CoreStateMachine::getcurrentVolume Mar 01 10:50:07 volumio volumio[1060]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 10:50:07 volumio volumio[1060]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 01 10:50:07 volumio volumio[1060]: info: VolumeController:: Volume=undefined Mute =false Mar 01 10:50:07 volumio volumio[1060]: info: CoreStateMachine::pushState Mar 01 10:50:07 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:07 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Mar 01 10:50:07 volumio volumio[1060]: info: CoreStateMachine::updateTrackBlock Mar 01 10:50:07 volumio volumio[1060]: info: CorePlayQueue::getTrackBlock Mar 01 10:50:07 volumio volumio[1060]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 10:50:07 volumio volumio[1060]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 01 10:50:07 volumio volumio[1060]: info: VolumeController:: Volume=undefined Mute =false Mar 01 10:50:07 volumio volumio[1060]: info: CoreStateMachine::pushState Mar 01 10:50:07 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:07 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Mar 01 10:50:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Mar 01 10:50:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 01 10:50:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Mar 01 10:50:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Mar 01 10:50:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 01 10:50:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 01 10:50:13 volumio volumio[1060]: info: CoreCommandRouter::Reload Ui Mar 01 10:50:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Mar 01 10:50:13 volumio volumio[1060]: info: Wizard terminated Successfully Mar 01 10:50:13 volumio volumio[1060]: info: CoreCommandRouter::volumiosetStartupVolume Mar 01 10:50:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 10:50:13 volumio volumio[1060]: info: Player Reboot required after I2S DAC has been enabled in wizard Mar 01 10:50:13 volumio sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 10:50:13 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:13 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:13 volumio sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 10:50:13 volumio sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:13 volumio sudo[1829]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:13 volumio volumio[1060]: verbose: New Socket.io Connection to 192.168.18.227 from 192.168.18.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 01 10:50:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 01 10:50:14 volumio sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 01 10:50:14 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:14 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:14 volumio sudo[1836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 10:50:14 volumio sudo[1836]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 10:50:14 volumio sudo[1836]: pam_unix(sudo:session): session closed for user root Mar 01 10:50:14 volumio volumio[1060]: verbose: New Socket.io Connection to 192.168.18.227 from 192.168.18.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:14 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 01 10:50:14 volumio volumio[1060]: info: Received Get System Info Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:50:14 volumio volumio[1060]: info: Discovery: Getting this device information Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:14 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:14 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:14 volumio volumio[1060]: info: Listing playlists Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 01 10:50:14 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 01 10:50:15 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:15 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:15 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 01 10:50:16 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 01 10:50:16 volumio volumio[1060]: info: Received Get System Info Mar 01 10:50:16 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:50:16 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:50:16 volumio volumio[1060]: info: Discovery: Getting this device information Mar 01 10:50:16 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:16 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:16 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:50:24 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 01 10:50:24 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 01 10:50:27 volumio volumio[1060]: info: CoreCommandRouter::volumioGetQueue Mar 01 10:50:27 volumio volumio[1060]: info: CoreStateMachine::getQueue Mar 01 10:50:27 volumio volumio[1060]: info: CorePlayQueue::getQueue Mar 01 10:50:30 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 10:50:30 volumio volumio[1060]: info: Preload queue cleared Mar 01 10:50:31 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 10:50:31 volumio volumio[1060]: info: Preload queue cleared Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 10:50:32 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 01 10:50:32 volumio volumio-remote-updater[690]: No test mode Mar 01 10:50:32 volumio volumio-remote-updater[690]: No alpha test mode Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 01 10:50:32 volumio volumio[1060]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 01 10:50:32 volumio volumio[1060]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 01 10:50:32 volumio volumio[1060]: info: Received Get System Version Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 01 10:50:32 volumio volumio[1060]: info: Received Get System Info Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 10:50:32 volumio volumio[1060]: info: Discovery: Getting this device information Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Mar 01 10:50:32 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Mar 01 10:50:32 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 10:50:33 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 10:50:33 volumio volumio[1060]: info: Preload queue cleared Mar 01 10:50:36 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 01 10:50:36 volumio volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 10:50:36 volumio volumio[1060]: TypeError: Cannot read property 'length' of undefined Mar 01 10:50:36 volumio volumio[1060]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 01 10:50:36 volumio volumio[1060]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 01 10:50:36 volumio volumio[1060]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 01 10:50:36 volumio volumio[1060]: at Parser.emit (events.js:315:20) Mar 01 10:50:36 volumio volumio[1060]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 01 10:50:36 volumio volumio[1060]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 01 10:50:36 volumio volumio[1060]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 01 10:50:36 volumio volumio[1060]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 01 10:50:36 volumio volumio[1060]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 01 10:50:36 volumio volumio[1060]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 01 10:50:36 volumio volumio[1060]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 01 10:50:36 volumio volumio[1060]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 01 10:50:36 volumio volumio[1060]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 01 10:50:36 volumio volumio[1060]: at IncomingMessage.emit (events.js:327:22) Mar 01 10:50:36 volumio volumio[1060]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 01 10:50:36 volumio volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 01 10:50:36 volumio volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 10:50:37 volumio sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 10:49 Mar 01 10:50:37 volumio sudo[1923]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"