-- Logs begin at Thu 2025-05-29 21:41:52 +07, end at Thu 2025-05-29 22:13:18 +07. -- May 29 22:12:00 volumio wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'Ying_2.4G' May 29 22:12:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 29 22:12:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 29 22:12:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 29 22:12:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 29 22:12:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 29 22:12:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 29 22:12:00 volumio wpa_supplicant[1045]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 29 22:12:03 volumio volumio[1261]: info: Volumio Network Manager: Network status updated: 1 May 29 22:12:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 29 22:12:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 29 22:12:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 29 22:12:06 volumio wpa_supplicant[1045]: wlan0: Trying to associate with SSID 'Ying_2.4G' May 29 22:12:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 29 22:12:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 29 22:12:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 29 22:12:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 29 22:12:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 29 22:12:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 29 22:12:06 volumio wpa_supplicant[1045]: wlan0: Associated with dc:ef:80:29:d1:54 May 29 22:12:06 volumio wpa_supplicant[1045]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:ef:80:29:d1:54 completed [id=1 id_str=] May 29 22:12:06 volumio dhcpcd[1118]: wlan0: carrier acquired May 29 22:12:06 volumio wpa_supplicant[1045]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 29 22:12:06 volumio dhcpcd[1118]: wlan0: connected to Access Point `Ying_2.4G' May 29 22:12:06 volumio wpa_supplicant[1045]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=TH May 29 22:12:06 volumio dhcpcd[1118]: wlan0: IAID 67:64:10:1f May 29 22:12:06 volumio dhcpcd[1118]: wlan0: carrier lost May 29 22:12:06 volumio dhcpcd[1118]: wlan0: carrier acquired May 29 22:12:06 volumio dhcpcd[1118]: wlan0: IAID 67:64:10:1f May 29 22:12:07 volumio dhcpcd[1118]: wlan0: rebinding lease of 192.168.1.129 May 29 22:12:07 volumio dhcpcd[1118]: wlan0: probing address 192.168.1.129/24 May 29 22:12:07 volumio dhcpcd[1118]: wlan0: soliciting an IPv6 router May 29 22:12:07 volumio volumio[1261]: verbose: New Socket.io Connection to 192.168.1.196 from 192.168.1.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_11 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 May 29 22:12:07 volumio volumio[1261]: info: Received Get System Info May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 22:12:07 volumio volumio[1261]: info: Discovery: Getting this device information May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:07 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:07 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetVisibleSources May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:07 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 22:12:07 volumio volumio[1261]: info: Received Get System Info May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 22:12:07 volumio volumio[1261]: info: Discovery: Getting this device information May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:07 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:07 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:07 volumio volumio[1261]: info: Listing playlists May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::volumioGetQueue May 29 22:12:07 volumio volumio[1261]: info: CoreStateMachine::getQueue May 29 22:12:07 volumio volumio[1261]: info: CorePlayQueue::getQueue May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:07 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 29 22:12:11 volumio dhcpcd[1118]: wlan0: leased 192.168.1.129 for 86400 seconds May 29 22:12:11 volumio avahi-daemon[716]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.129. May 29 22:12:11 volumio dhcpcd[1118]: wlan0: adding route to 192.168.1.0/24 May 29 22:12:11 volumio dhcpcd[1118]: wlan0: adding default route via 192.168.1.1 May 29 22:12:11 volumio avahi-daemon[716]: New relevant interface wlan0.IPv4 for mDNS. May 29 22:12:11 volumio avahi-daemon[716]: Registering new address record for 192.168.1.129 on wlan0.IPv4. May 29 22:12:11 volumio wpa_supplicant[1045]: RRM: Ignoring radio measurement request: Not RRM network May 29 22:12:11 volumio wpa_supplicant[1045]: RRM: Ignoring radio measurement request: Not RRM network May 29 22:12:12 volumio volumio[1261]: info: Discovery: adding 05c42601-2fde-4faf-92f8-3aca292794a2 May 29 22:12:12 volumio volumio[1261]: info: Discovery: Found device Volumio May 29 22:12:12 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:12 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:12 volumio ntpd[939]: Listen normally on 5 wlan0 192.168.1.129:123 May 29 22:12:12 volumio ntpd[939]: new interface(s) found: waking up resolver May 29 22:12:13 volumio volumio[1261]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_11 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 May 29 22:12:13 volumio volumio[1261]: info: Volumio Network Manager: Network status updated: 3 May 29 22:12:16 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 29 22:12:18 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:18 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:18 volumio volumio[1261]: info: Check plugin dependencies May 29 22:12:18 volumio volumio[1261]: error: Error finding native modules: Command failed: find /data/temp/downloaded_plugin/node_modules -name obj.target -prune -false -o -type f -name "*.node" 2>/dev/null {"status":1,"signal":null,"output":[null,"",""],"pid":11609,"stdout":"","stderr":"","stack":"Error: Command failed: find /data/temp/downloaded_plugin/node_modules -name obj.target -prune -false -o -type f -name \"*.node\" 2>/dev/null\n at checkExecSyncError (child_process.js:616:11)\n at execSync (child_process.js:652:15)\n at PluginManager.checkPluginDependencies (/volumio/app/pluginmanager.js:1149:26)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 29 22:12:18 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:18 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:18 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:18 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:18 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:18 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:18 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:18 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:18 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:18 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:18 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:18 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:18 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:18 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:18 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:18 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:18 volumio volumio[1261]: info: Error: Error May 29 22:12:18 volumio volumio[1261]: info: Error: Error May 29 22:12:18 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:18 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:18 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:18 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:19 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:20 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:20 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:20 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:20 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:20 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:20 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:20 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:20 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:20 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:20 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:20 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:20 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:20 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:20 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:20 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:20 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:20 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:20 volumio volumio[1261]: info: Error: Error May 29 22:12:20 volumio volumio[1261]: info: Error: Error May 29 22:12:20 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:20 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:20 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:20 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:20 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:21 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:22 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:22 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:22 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:22 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:22 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:22 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:22 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:22 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:22 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:22 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:22 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:22 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:22 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:22 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:22 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:22 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:22 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:22 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:22 volumio volumio[1261]: info: Error: Error May 29 22:12:22 volumio volumio[1261]: info: Error: Error May 29 22:12:22 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:22 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:22 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:22 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:23 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:23 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:23 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:23 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:23 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:23 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:23 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:23 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:23 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:23 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:23 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:23 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:23 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:23 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:23 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:23 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:23 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:23 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:23 volumio volumio[1261]: info: Error: Error May 29 22:12:23 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:23 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:23 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:23 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:24 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:24 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:24 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:24 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:24 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:24 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:24 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:24 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:24 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:24 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:24 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:24 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:24 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:24 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:24 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:24 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:24 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:24 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:24 volumio volumio[1261]: info: Error: Error May 29 22:12:24 volumio volumio[1261]: info: Error: Error May 29 22:12:24 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:24 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:24 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:24 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 22:12:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 22:12:24 volumio volumio[1261]: info: Discovery: Getting this device information May 29 22:12:24 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:24 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:24 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 22:12:25 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:25 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:25 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:25 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:25 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:25 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:25 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:25 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:25 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:25 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:25 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:25 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:25 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:25 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:25 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:25 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:25 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:25 volumio volumio[1261]: info: Error: Error May 29 22:12:25 volumio volumio[1261]: info: Error: Error May 29 22:12:25 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:25 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:25 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:25 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:26 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:26 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:26 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:26 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:26 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:26 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:26 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:26 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:26 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:26 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:26 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:26 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:26 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:26 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:26 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:26 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:26 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:26 volumio volumio[1261]: info: Error: Error May 29 22:12:26 volumio volumio[1261]: info: Error: Error May 29 22:12:26 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:26 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:26 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:26 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:26 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:26 volumio volumio[1261]: info: An error occurred installing the plugin. Rolling back config May 29 22:12:26 volumio volumio[1261]: info: Plugin folders cleanup May 29 22:12:26 volumio volumio[1261]: info: Scanning into folder /volumio/app/plugins/ May 29 22:12:26 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:26 volumio volumio[1261]: info: Scanning category miscellanea May 29 22:12:26 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:26 volumio volumio[1261]: info: Scanning category plugins.json May 29 22:12:26 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:26 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:26 volumio volumio[1261]: info: Scanning into folder /data/plugins/ May 29 22:12:26 volumio volumio[1261]: info: Scanning category audio_interface May 29 22:12:26 volumio volumio[1261]: info: Scanning category music_service May 29 22:12:26 volumio volumio[1261]: info: Scanning category system_controller May 29 22:12:26 volumio volumio[1261]: info: Scanning category system_hardware May 29 22:12:26 volumio volumio[1261]: info: Scanning category user_interface May 29 22:12:26 volumio volumio[1261]: info: Plugin folders cleanup completed May 29 22:12:26 volumio volumio[1261]: info: Error: Error May 29 22:12:26 volumio volumio[1261]: info: Error: Error May 29 22:12:26 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:26 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:26 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:26 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:26 volumio volumio[1261]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:28 volumio volumio[1261]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/mpdoutput/volumio/buster/armhf May 29 22:12:28 volumio volumio[1261]: info: Check plugin dependencies May 29 22:12:28 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 22:12:28 volumio volumio[1261]: info: Rename folder May 29 22:12:28 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:28 volumio volumio[1261]: info: Move to category May 29 22:12:28 volumio volumio[1261]: info: Checking if install.sh is present May 29 22:12:28 volumio volumio[1261]: info: Executing install.sh May 29 22:12:28 volumio sudo[11737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/install.sh May 29 22:12:28 volumio sudo[11737]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:28 volumio sudo[11737]: pam_unix(sudo:session): session closed for user root May 29 22:12:28 volumio volumio[1261]: info: Installing mpd http output May 29 22:12:28 volumio volumio[1261]: Plugin install end detected on script May 29 22:12:28 volumio volumio[1261]: info: Install script completed May 29 22:12:28 volumio volumio[1261]: info: Done installing plugin. May 29 22:12:28 volumio volumio[1261]: info: Enabling plugin mpdoutput May 29 22:12:28 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 22:12:28 volumio volumio[1261]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"user_interface","version":"2.2.1","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] May 29 22:12:28 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 22:12:28 volumio volumio[1261]: info: Folder /tmp/plugins removed May 29 22:12:28 volumio volumio[1261]: info: Folder /tmp/downloaded_plugin.zip removed May 29 22:12:28 volumio volumio[1261]: info: Folder /data/temp removed May 29 22:12:28 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:31 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 22:12:31 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 22:12:32 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:46 volumio volumio[1261]: info: Enabling plugin peppy_screensaver May 29 22:12:46 volumio volumio[1261]: info: Loading plugin "peppy_screensaver"... May 29 22:12:46 volumio volumio[1261]: info: Applying required configuration parameters for plugin peppy_screensaver May 29 22:12:46 volumio volumio[1261]: info: Preparing to generate the ALSA configuration file May 29 22:12:46 volumio volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 22:12:46 volumio volumio[1261]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 29 22:12:46 volumio volumio[1261]: info: Reading ALSA contributions from plugins. May 29 22:12:46 volumio volumio[1261]: info: Asound.conf file written May 29 22:12:46 volumio sudo[11778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 29 22:12:46 volumio sudo[11778]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:46 volumio sudo[11778]: pam_unix(sudo:session): session closed for user root May 29 22:12:46 volumio volumio[1261]: info: Output device has changed, restarting MPD May 29 22:12:46 volumio sudo[11784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 22:12:46 volumio sudo[11784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:46 volumio sudo[11784]: pam_unix(sudo:session): session closed for user root May 29 22:12:46 volumio volumio[1261]: info: Output device has changed, restarting Shairport Sync May 29 22:12:46 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:46 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:12:46 volumio sudo[11787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 22:12:46 volumio sudo[11787]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:46 volumio systemd[1]: Stopping Music Player Daemon... May 29 22:12:46 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:12:46 volumio volumio[1261]: info: PLUGIN START: peppy_screensaver May 29 22:12:46 volumio volumio[1261]: info: Loading i18n strings for locale en May 29 22:12:46 volumio systemd[1]: mpd.service: Succeeded. May 29 22:12:46 volumio systemd[1]: Stopped Music Player Daemon. May 29 22:12:46 volumio volumio[1261]: info: /tmp/myfifo created May 29 22:12:46 volumio volumio[1261]: info: /tmp/myfifosa created May 29 22:12:46 volumio systemd[1]: Starting Music Player Daemon... May 29 22:12:46 volumio sudo[11798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 May 29 22:12:46 volumio sudo[11798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:46 volumio sudo[11798]: pam_unix(sudo:session): session closed for user root May 29 22:12:46 volumio volumio[1261]: info: snd-dummy loaded May 29 22:12:46 volumio sudo[11792]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 22:12:46 volumio sudo[11792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:46 volumio sudo[11792]: pam_unix(sudo:session): session closed for user root May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:12:47 volumio volumio[1261]: info: Done. May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:47 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:47 volumio volumio[1261]: info: MPD Permissions set May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:12:47 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:12:47 volumio sudo[11820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:12:47 volumio sudo[11820]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:12:47 volumio sudo[11823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:12:47 volumio sudo[11823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:47 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:12:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:12:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:12:47 volumio sudo[11820]: pam_unix(sudo:session): session closed for user root May 29 22:12:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:12:47 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 29 22:12:47 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:12:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:12:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:12:47 volumio sudo[11823]: pam_unix(sudo:session): session closed for user root May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:47 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:12:47 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:12:47 volumio sudo[11831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 29 22:12:47 volumio sudo[11831]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:47 volumio sudo[11831]: pam_unix(sudo:session): session closed for user root May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio sudo[11836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio sudo[11836]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:47 volumio sudo[11836]: pam_unix(sudo:session): session closed for user root May 29 22:12:47 volumio volumio[1261]: info: MPD Permissions set May 29 22:12:47 volumio mpd[11802]: May 29 22:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 22:12:47 volumio systemd[1]: Started Music Player Daemon. May 29 22:12:47 volumio sudo[11787]: pam_unix(sudo:session): session closed for user root May 29 22:12:47 volumio volumio[1261]: error: updateQueue error: null May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:12:47 volumio volumio[1261]: info: peppy_screensaver: Start PeppyMeter May 29 22:12:47 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 29 22:12:47 volumio sudo[11849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 22:12:47 volumio sudo[11849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:47 volumio systemd[1]: Stopping Music Player Daemon... May 29 22:12:47 volumio systemd[1]: mpd.service: Succeeded. May 29 22:12:47 volumio systemd[1]: Stopped Music Player Daemon. May 29 22:12:47 volumio systemd[1]: Starting Music Player Daemon... May 29 22:12:47 volumio sudo[11851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 22:12:47 volumio sudo[11851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:12:47 volumio sudo[11851]: pam_unix(sudo:session): session closed for user root May 29 22:12:48 volumio mpd[11853]: May 29 22:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 22:12:48 volumio systemd[1]: Started Music Player Daemon. May 29 22:12:48 volumio sudo[11849]: pam_unix(sudo:session): session closed for user root May 29 22:12:48 volumio volumio[1261]: error: updateQueue error: null May 29 22:12:50 volumio volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 22:12:50 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:12:51 volumio volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 May 29 22:12:51 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:51 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:52 volumio volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 May 29 22:12:52 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:52 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:54 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 22:12:54 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 22:12:54 volumio volumio[1261]: info: Discovery: Getting this device information May 29 22:12:54 volumio volumio[1261]: info: CoreCommandRouter::volumioGetState May 29 22:12:54 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:12:54 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 22:13:00 volumio volumio[1261]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:00 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon May 29 22:13:01 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:13:01 volumio sudo[11949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:13:01 volumio sudo[11949]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:13:01 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:13:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:13:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:13:01 volumio sudo[11949]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 29 22:13:01 volumio volumio[1261]: info: Preparing to generate the ALSA configuration file May 29 22:13:01 volumio volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 22:13:01 volumio volumio[1261]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 29 22:13:01 volumio volumio[1261]: info: Reading ALSA contributions from plugins. May 29 22:13:01 volumio volumio[1261]: info: Asound.conf file written May 29 22:13:01 volumio sudo[11956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 29 22:13:01 volumio sudo[11956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio sudo[11956]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: info: Output device has changed, restarting MPD May 29 22:13:01 volumio sudo[11961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 22:13:01 volumio sudo[11961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio sudo[11961]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: info: Output device has changed, restarting Shairport Sync May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:01 volumio sudo[11964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 22:13:01 volumio sudo[11964]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio systemd[1]: Stopping Music Player Daemon... May 29 22:13:01 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:13:01 volumio volumio[1261]: info: MPD Permissions set May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio systemd[1]: mpd.service: Succeeded. May 29 22:13:01 volumio systemd[1]: Stopped Music Player Daemon. May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:13:01 volumio systemd[1]: Starting Music Player Daemon... May 29 22:13:01 volumio sudo[11975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:13:01 volumio sudo[11975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:13:01 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:13:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:13:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:13:01 volumio sudo[11975]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:13:01 volumio sudo[11969]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 22:13:01 volumio sudo[11969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio sudo[11969]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio mpd[11981]: May 29 22:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 22:13:01 volumio systemd[1]: Started Music Player Daemon. May 29 22:13:01 volumio sudo[11964]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: error: updateQueue error: null May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 29 22:13:01 volumio sudo[11989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 22:13:01 volumio sudo[11989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio systemd[1]: Stopping Music Player Daemon... May 29 22:13:01 volumio systemd[1]: mpd.service: Succeeded. May 29 22:13:01 volumio systemd[1]: Stopped Music Player Daemon. May 29 22:13:01 volumio systemd[1]: Starting Music Player Daemon... May 29 22:13:01 volumio sudo[11991]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 22:13:01 volumio sudo[11991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:01 volumio sudo[11991]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:01 volumio mpd[11993]: May 29 22:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 22:13:01 volumio systemd[1]: Started Music Player Daemon. May 29 22:13:01 volumio sudo[11989]: pam_unix(sudo:session): session closed for user root May 29 22:13:01 volumio volumio[1261]: error: updateQueue error: null May 29 22:13:02 volumio volumio[1261]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] May 29 22:13:02 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf May 29 22:13:02 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:02 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:02 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 29 22:13:02 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:02 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:02 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:13:02 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:13:02 volumio sudo[12000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:13:02 volumio sudo[12000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:13:02 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:13:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:13:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:13:02 volumio sudo[12000]: pam_unix(sudo:session): session closed for user root May 29 22:13:02 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:13:05 volumio volumio[1261]: info: CoreCommandRouter::volumioPlay May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::play index undefined May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 22:13:05 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::startPlaybackTimer May 29 22:13:05 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/iMusic/Audiophile Reference 5/10 Various Artists - ­ì³¥¬O§Ú®a.flac May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand stop May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand stop took 1 milliseconds May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand clear May 29 22:13:05 volumio volumio[1261]: info: May 29 22:13:05 volumio volumio[1261]: ---------------------------- MPD announces system playlist update May 29 22:13:05 volumio volumio[1261]: info: Ignoring MPD Status Update May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand clear took 0 milliseconds May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/iMusic/Audiophile Reference 5/10 Various Artists - ­ì³¥¬O§Ú®a.flac" May 29 22:13:05 volumio volumio[1261]: error: updateQueue error: null May 29 22:13:05 volumio volumio[1261]: info: ------------------------------ 0ms May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand add "INTERNAL/iMusic/Audiophile Reference 5/10 Various Artists - ­ì³¥¬O§Ú®a.flac" took 0 milliseconds May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand play May 29 22:13:05 volumio volumio[1261]: info: May 29 22:13:05 volumio volumio[1261]: ---------------------------- MPD announces system playlist update May 29 22:13:05 volumio volumio[1261]: info: Ignoring MPD Status Update May 29 22:13:05 volumio volumio[1261]: info: May 29 22:13:05 volumio volumio[1261]: ---------------------------- MPD announces state update: player May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand play took 1 milliseconds May 29 22:13:05 volumio volumio[1261]: info: ControllerMpd::getState May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status May 29 22:13:05 volumio volumio[1261]: info: ------------------------------ 1ms May 29 22:13:05 volumio volumio[1261]: info: May 29 22:13:05 volumio volumio[1261]: ---------------------------- MPD announces state update: player May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand clearerror May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand status took 8 milliseconds May 29 22:13:05 volumio volumio[1261]: info: ControllerMpd::getState May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand status May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::parseState May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand clearerror took 1 milliseconds May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand status took 1 milliseconds May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::parseState May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo May 29 22:13:05 volumio volumio[1261]: info: ControllerMpd::pushState May 29 22:13:05 volumio volumio[1261]: info: CoreCommandRouter::servicePushState May 29 22:13:05 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:13:05 volumio volumio[1261]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"­ì³¥¬O§Ú®a","artist":"Various Artists","album":"Audiophile Reference 5","uri":"INTERNAL/iMusic/Audiophile Reference 5/10 Various Artists - ­ì³¥¬O§Ú®a.flac","trackType":"flac"} May 29 22:13:05 volumio volumio[1261]: verbose: CURRENT POSITION 9 May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::syncState stateService pause May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::pushState May 29 22:13:05 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:13:05 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState May 29 22:13:05 volumio volumio[1261]: info: ------------------------------ 14ms May 29 22:13:05 volumio volumio[1261]: info: sendMpdCommand playlistinfo took 6 milliseconds May 29 22:13:05 volumio volumio[1261]: verbose: ControllerMpd::parseTrackInfo May 29 22:13:05 volumio volumio[1261]: info: ControllerMpd::pushState May 29 22:13:05 volumio volumio[1261]: info: CoreCommandRouter::servicePushState May 29 22:13:05 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:13:05 volumio volumio[1261]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"­ì³¥¬O§Ú®a","artist":"Various Artists","album":"Audiophile Reference 5","uri":"INTERNAL/iMusic/Audiophile Reference 5/10 Various Artists - ­ì³¥¬O§Ú®a.flac","trackType":"flac"} May 29 22:13:05 volumio volumio[1261]: verbose: CURRENT POSITION 9 May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::syncState stateService pause May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::syncState currentStatus stop May 29 22:13:05 volumio volumio[1261]: info: CoreStateMachine::pushState May 29 22:13:05 volumio volumio[1261]: info: CorePlayQueue::getTrack 9 May 29 22:13:05 volumio volumio[1261]: info: CoreCommandRouter::volumioPushState May 29 22:13:05 volumio volumio[1261]: info: ------------------------------ 14ms May 29 22:13:12 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 22:13:12 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 22:13:17 volumio volumio[1261]: info: Enabling plugin fusiondsp May 29 22:13:17 volumio volumio[1261]: info: Loading plugin "fusiondsp"... May 29 22:13:17 volumio volumio[1261]: info: Applying required configuration parameters for plugin fusiondsp May 29 22:13:17 volumio volumio[1261]: info: Preparing to generate the ALSA configuration file May 29 22:13:17 volumio volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 22:13:17 volumio volumio[1261]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 29 22:13:17 volumio volumio[1261]: info: Reading ALSA contributions from plugins. May 29 22:13:17 volumio volumio[1261]: info: Asound.conf file unchanged, so no further update is needed May 29 22:13:17 volumio volumio[1261]: info: Output device has changed, restarting MPD May 29 22:13:17 volumio sudo[12055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 22:13:17 volumio sudo[12055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio sudo[12055]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio volumio[1261]: info: Output device has changed, restarting Shairport Sync May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:17 volumio sudo[12058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 22:13:17 volumio sudo[12058]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio systemd[1]: Stopping Music Player Daemon... May 29 22:13:17 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:13:17 volumio volumio[1261]: info: PLUGIN START: fusiondsp May 29 22:13:17 volumio systemd[1]: mpd.service: Succeeded. May 29 22:13:17 volumio systemd[1]: Stopped Music Player Daemon. May 29 22:13:17 volumio volumio[1261]: info: Loading i18n strings for locale en May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: FusionDsp - mixtype--------------------- undefined May 29 22:13:17 volumio volumio[1261]: info: Preparing to generate the ALSA configuration file May 29 22:13:17 volumio volumio[1261]: info: Done. May 29 22:13:17 volumio volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 22:13:17 volumio volumio[1261]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 29 22:13:17 volumio volumio[1261]: info: Reading ALSA contributions from plugins. May 29 22:13:17 volumio volumio[1261]: info: MPD Permissions set May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:13:17 volumio systemd[1]: Starting Music Player Daemon... May 29 22:13:17 volumio volumio[1261]: info: Asound.conf file unchanged, so no further update is needed May 29 22:13:17 volumio volumio[1261]: info: Output device has changed, restarting MPD May 29 22:13:17 volumio sudo[12069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:13:17 volumio sudo[12069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:13:17 volumio sudo[12072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 22:13:17 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:13:17 volumio sudo[12072]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:13:17 volumio sudo[12072]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:13:17 volumio volumio[1261]: info: Output device has changed, restarting Shairport Sync May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:17 volumio sudo[12063]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 22:13:17 volumio sudo[12063]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio sudo[12075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 22:13:17 volumio sudo[12063]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio sudo[12069]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio sudo[12075]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 29 22:13:17 volumio systemd[1]: mpd.service: Succeeded. May 29 22:13:17 volumio systemd[1]: Stopped Music Player Daemon. May 29 22:13:17 volumio systemd[1]: Starting Music Player Daemon... May 29 22:13:17 volumio volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 22:13:17 volumio volumio[1261]: info: MPD Permissions set May 29 22:13:17 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:13:17 volumio volumio[1261]: (node:1261) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 29 22:13:17 volumio volumio[1261]: (Use `node --trace-warnings ...` to show where the warning was created) May 29 22:13:17 volumio volumio[1261]: (node:1261) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 29 22:13:17 volumio volumio[1261]: (node:1261) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 29 22:13:17 volumio volumio[1261]: (node:1261) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 29 22:13:17 volumio volumio[1261]: (node:1261) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: Starting Shairport Sync May 29 22:13:17 volumio sudo[12085]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 22:13:17 volumio sudo[12085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio sudo[12085]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio sudo[12092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 22:13:17 volumio sudo[12092]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 22:13:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 22:13:17 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 22:13:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 22:13:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 22:13:17 volumio sudo[12092]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio volumio[1261]: info: Shairport-Sync Started May 29 22:13:17 volumio mpd[12093]: May 29 22:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 22:13:17 volumio systemd[1]: Started Music Player Daemon. May 29 22:13:17 volumio sudo[12058]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio sudo[12075]: pam_unix(sudo:session): session closed for user root May 29 22:13:17 volumio volumio[1261]: error: MPD error: The expression evaluated to a falsy value: May 29 22:13:17 volumio volumio[1261]: assert.ok(self.idling) May 29 22:13:17 volumio volumio[1261]: error: The expression evaluated to a falsy value: May 29 22:13:17 volumio volumio[1261]: assert.ok(self.idling) May 29 22:13:17 volumio volumio[1261]: error: updateQueue error: null May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:17 volumio volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 22:13:18 volumio volumio[1261]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 22:13:18 volumio volumio[1261]: TypeError: Cannot read property 'off' of undefined May 29 22:13:18 volumio volumio[1261]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 29 22:13:18 volumio volumio[1261]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 29 22:13:18 volumio volumio[1261]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 29 22:13:18 volumio volumio[1261]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 29 22:13:18 volumio volumio[1261]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 29 22:13:18 volumio volumio[1261]: at Socket.emit (events.js:315:20) May 29 22:13:18 volumio volumio[1261]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 29 22:13:18 volumio volumio[1261]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 29 22:13:18 volumio volumio[1261]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 22:13:18 volumio sudo[12114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 22:12 May 29 22:13:18 volumio sudo[12114]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"