-- Logs begin at Thu 2019-02-14 11:12:03 CET, end at Wed 2024-07-24 16:28:09 CEST. -- Jul 24 16:27:00 rpi-test volumio[885]: info: Selecting previously unselected package python3-wheel. Jul 24 16:27:00 rpi-test volumio[885]: info: Preparing to unpack .../49-python3-wheel_0.32.3-2_all.deb ... Jul 24 16:27:00 rpi-test volumio[885]: info: Unpacking python3-wheel (0.32.3-2) ... Jul 24 16:27:01 rpi-test volumio[885]: info: Selecting previously unselected package python3-xdg. Jul 24 16:27:01 rpi-test volumio[885]: info: Preparing to unpack .../50-python3-xdg_0.25-5_all.deb ... Jul 24 16:27:01 rpi-test volumio[885]: info: Unpacking python3-xdg (0.25-5) ... Jul 24 16:27:01 rpi-test volumio[885]: info: Setting up python3-entrypoints (0.3-1) ... Jul 24 16:27:02 rpi-test sudo[4312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 24 16:27:02 rpi-test sudo[4312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:03 rpi-test sudo[4312]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:03 rpi-test sudo[4314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 24 16:27:03 rpi-test sudo[4314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:03 rpi-test sudo[4314]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:03 rpi-test volumio[885]: info: Setting up dh-python (3.20190308) ... Jul 24 16:27:04 rpi-test volumio[885]: verbose: New Socket.io Connection to 192.168.50.115 from 192.168.50.118 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jul 24 16:27:05 rpi-test volumio[885]: info: Setting up python3-attr (18.2.0-1) ... Jul 24 16:27:06 rpi-test volumio[885]: verbose: New Socket.io Connection to 192.168.50.115 from 192.168.50.118 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jul 24 16:27:07 rpi-test volumio[885]: info: Setting up python3-dbus (1.2.8-3) ... Jul 24 16:27:08 rpi-test volumio[885]: info: Setting up python3-setuptools (40.8.0-1) ... Jul 24 16:27:08 rpi-test volumio[885]: verbose: New Socket.io Connection to 192.168.50.115 from 192.168.50.118 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 16:27:08 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:08 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: Listing playlists Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 24 16:27:08 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:08 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 16:27:08 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:08 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:08 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:08 rpi-test volumio[885]: info: Listing playlists Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 24 16:27:08 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 24 16:27:09 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:09 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:09 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:09 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:09 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:09 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:10 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 24 16:27:11 rpi-test volumio[885]: info: Setting up libpython3.7:armhf (3.7.3-2+deb10u7) ... Jul 24 16:27:11 rpi-test volumio[885]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Jul 24 16:27:11 rpi-test volumio[885]: info: Setting up python3-xdg (0.25-5) ... Jul 24 16:27:13 rpi-test volumio[885]: info: Setting up bzip2 (1.0.6-9.2~deb10u2) ... Jul 24 16:27:13 rpi-test volumio[885]: info: Setting up python3-wheel (0.32.3-2) ... Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:14 rpi-test volumio[885]: info: Retrieving Cloud Streaming UI Jul 24 16:27:14 rpi-test volumio[885]: info: Getting Tidal Cloud Configuration Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:14 rpi-test volumio[885]: info: Getting Qobuz Cloud Configuration Jul 24 16:27:14 rpi-test volumio[885]: info: Asking plugin for UI Config Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:14 rpi-test volumio[885]: info: Getting Spotify Cloud Configuration Jul 24 16:27:14 rpi-test volumio[885]: info: Asking plugin for UI Config Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:14 rpi-test volumio[885]: info: Saving Spotify Acccount Jul 24 16:27:14 rpi-test volumio[885]: info: Got Tidal Cloud Configuration Jul 24 16:27:14 rpi-test volumio[885]: info: Got it Jul 24 16:27:14 rpi-test volumio[885]: info: Got it Jul 24 16:27:14 rpi-test volumio[885]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:14 rpi-test volumio[885]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:14 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 16:27:15 rpi-test volumio[885]: info: Setting up python3-multidict (4.5.2-1) ... Jul 24 16:27:16 rpi-test volumio[885]: info: Setting up libfakeroot:armhf (1.23-1) ... Jul 24 16:27:16 rpi-test volumio[885]: info: Setting up python3-async-timeout (3.0.1-1) ... Jul 24 16:27:18 rpi-test volumio[885]: info: Setting up fakeroot (1.23-1) ... Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Jul 24 16:27:18 rpi-test volumio[885]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Jul 24 16:27:18 rpi-test volumio[885]: info: Setting up libasan5:armhf (8.3.0-6+rpi1) ... Jul 24 16:27:18 rpi-test volumio[885]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u7) ... Jul 24 16:27:18 rpi-test volumio[885]: info: Setting up make (4.2.1-1.2) ... Jul 24 16:27:18 rpi-test volumio[885]: info: Setting up libmpfr6:armhf (4.0.2-1) ... Jul 24 16:27:18 rpi-test volumio[885]: info: Setting up python3-gi (3.30.4-1) ... Jul 24 16:27:18 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:19 rpi-test volumio[885]: info: Disabling MyMusic plugin airplay_emulation Jul 24 16:27:19 rpi-test volumio[885]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Jul 24 16:27:19 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 16:27:19 rpi-test volumio[885]: info: Disabling plugin airplay_emulation Jul 24 16:27:19 rpi-test volumio[885]: info: Done. Jul 24 16:27:19 rpi-test sudo[4430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Jul 24 16:27:19 rpi-test sudo[4430]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:19 rpi-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 24 16:27:19 rpi-test systemd[1]: shairport-sync.service: Succeeded. Jul 24 16:27:19 rpi-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 24 16:27:19 rpi-test sudo[4430]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:19 rpi-test volumio[885]: info: Shairport-Sync Stopped Jul 24 16:27:19 rpi-test volumio[885]: info: Enabling MyMusic plugin airplay_emulation Jul 24 16:27:19 rpi-test volumio[885]: info: Enabling plugin airplay_emulation Jul 24 16:27:19 rpi-test volumio[885]: info: Loading plugin "airplay_emulation"... Jul 24 16:27:19 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:27:19 rpi-test volumio[885]: info: PLUGIN START: airplay_emulation Jul 24 16:27:19 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:27:19 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:27:19 rpi-test volumio[885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 24 16:27:19 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:27:19 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:27:19 rpi-test volumio[885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 24 16:27:19 rpi-test volumio[885]: info: Done. Jul 24 16:27:19 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:27:20 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:27:20 rpi-test sudo[4447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 24 16:27:20 rpi-test sudo[4447]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:20 rpi-test sudo[4449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 24 16:27:20 rpi-test sudo[4449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:20 rpi-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 24 16:27:20 rpi-test sudo[4447]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:20 rpi-test volumio[885]: info: Shairport-Sync Started Jul 24 16:27:20 rpi-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 24 16:27:20 rpi-test systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 24 16:27:20 rpi-test systemd[1]: shairport-sync.service: Succeeded. Jul 24 16:27:20 rpi-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 24 16:27:20 rpi-test volumio[885]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 24 16:27:20 rpi-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 24 16:27:20 rpi-test sudo[4449]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:20 rpi-test volumio[885]: info: Received Get System Version Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 24 16:27:20 rpi-test volumio[885]: info: Shairport-Sync Started Jul 24 16:27:20 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:20 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:20 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:20 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:20 rpi-test volumio[885]: info: Setting up libmpc3:armhf (1.1.0-1) ... Jul 24 16:27:20 rpi-test volumio[885]: info: Setting up patch (2.7.6-3+deb10u1) ... Jul 24 16:27:21 rpi-test volumio[885]: info: Setting up libubsan1:armhf (8.3.0-6+rpi1) ... Jul 24 16:27:21 rpi-test volumio[885]: info: Setting up libisl19:armhf (0.20-2) ... Jul 24 16:27:21 rpi-test volumio[885]: info: Setting up python3-crypto (2.6.1-9+b1) ... Jul 24 16:27:22 rpi-test volumio[885]: info: Enabling MyMusic plugin multiroom Jul 24 16:27:22 rpi-test volumio[885]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Jul 24 16:27:22 rpi-test volumio[885]: info: [MyVolumio PluginManager] Plugin audio_interface multiroom not in user plan, not enabling Jul 24 16:27:22 rpi-test volumio[885]: info: Error: Plugin audio_interface multiroom not in user plan, not enabling Jul 24 16:27:22 rpi-test volumio[885]: error: Could not Enable MyMusic Plugin: Error Jul 24 16:27:22 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:24 rpi-test volumio[885]: info: Setting up python-pip-whl (18.1-5+rpt1) ... Jul 24 16:27:24 rpi-test volumio[885]: info: Setting up cpp-8 (8.3.0-6+rpi1) ... Jul 24 16:27:24 rpi-test volumio[885]: info: Setting up python3-yarl (1.3.0-1) ... Jul 24 16:27:25 rpi-test volumio[885]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Jul 24 16:27:25 rpi-test volumio[885]: info: Setting up python3-asn1crypto (0.24.0-1) ... Jul 24 16:27:26 rpi-test volumio[885]: info: Enabling MyMusic plugin bluetooth Jul 24 16:27:26 rpi-test volumio[885]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Jul 24 16:27:26 rpi-test volumio[885]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Jul 24 16:27:26 rpi-test volumio[885]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Jul 24 16:27:26 rpi-test volumio[885]: error: Could not Enable MyMusic Plugin: Error Jul 24 16:27:26 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:27 rpi-test volumio[885]: info: Setting up libcc1-0:armhf (8.3.0-6+rpi1) ... Jul 24 16:27:28 rpi-test volumio[885]: info: Enabling MyMusic plugin cd_controller Jul 24 16:27:28 rpi-test volumio[885]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service cd_controller Jul 24 16:27:28 rpi-test volumio[885]: info: [MyVolumio PluginManager] Plugin music_service cd_controller not in user plan, not enabling Jul 24 16:27:28 rpi-test volumio[885]: info: Error: Plugin music_service cd_controller not in user plan, not enabling Jul 24 16:27:28 rpi-test volumio[885]: error: Could not Enable MyMusic Plugin: Error Jul 24 16:27:28 rpi-test volumio[885]: info: Setting up python3-cffi-backend (1.12.2-1) ... Jul 24 16:27:28 rpi-test volumio[885]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Jul 24 16:27:28 rpi-test volumio[885]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Jul 24 16:27:28 rpi-test volumio[885]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... Jul 24 16:27:30 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:31 rpi-test volumio[885]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... Jul 24 16:27:31 rpi-test volumio[885]: info: Setting up dpkg-dev (1.19.8) ... Jul 24 16:27:31 rpi-test volumio[885]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... Jul 24 16:27:34 rpi-test volumio[885]: info: Setting up python3-pip (18.1-5+rpt1) ... Jul 24 16:27:34 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:36 rpi-test volumio[885]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... Jul 24 16:27:36 rpi-test volumio[885]: info: Setting up cpp (4:8.3.0-1+rpi2) ... Jul 24 16:27:37 rpi-test volumio[885]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Jul 24 16:27:38 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 16:27:39 rpi-test volumio[885]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Jul 24 16:27:39 rpi-test volumio[885]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... Jul 24 16:27:39 rpi-test volumio[885]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Jul 24 16:27:39 rpi-test volumio[885]: info: Setting up gcc (4:8.3.0-1+rpi2) ... Jul 24 16:27:39 rpi-test volumio[885]: info: Setting up python3-secretstorage (2.3.1-2) ... Jul 24 16:27:40 rpi-test volumio[885]: info: Setting up python3-dev (3.7.3-1) ... Jul 24 16:27:40 rpi-test volumio[885]: info: Setting up g++-8 (8.3.0-6+rpi1) ... Jul 24 16:27:40 rpi-test volumio[885]: info: Setting up python3-keyring (17.1.1-1) ... Jul 24 16:27:42 rpi-test volumio[885]: info: Setting up g++ (4:8.3.0-1+rpi2) ... Jul 24 16:27:42 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:27:42 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 24 16:27:42 rpi-test volumio[885]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Jul 24 16:27:42 rpi-test volumio[885]: info: Setting up build-essential (12.6) ... Jul 24 16:27:42 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 24 16:27:42 rpi-test sudo[4580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 24 16:27:42 rpi-test sudo[4580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:43 rpi-test sudo[4586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 24 16:27:43 rpi-test sudo[4586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:43 rpi-test sudo[4580]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:43 rpi-test sudo[4586]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:43 rpi-test sudo[4596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 24 16:27:43 rpi-test sudo[4596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:43 rpi-test sudo[4600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 24 16:27:43 rpi-test sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:43 rpi-test sudo[4596]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:43 rpi-test sudo[4600]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:43 rpi-test sudo[4607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 24 16:27:43 rpi-test sudo[4607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:43 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 24 16:27:43 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 24 16:27:43 rpi-test sudo[4607]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:43 rpi-test sudo[4609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 24 16:27:43 rpi-test sudo[4609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:43 rpi-test sudo[4609]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:43 rpi-test volumio[885]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Jul 24 16:27:43 rpi-test sudo[4613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 24 16:27:43 rpi-test sudo[4613]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:47 rpi-test sudo[4613]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:51 rpi-test sudo[4671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 24 16:27:51 rpi-test sudo[4671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:51 rpi-test sudo[4671]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:51 rpi-test sudo[4673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 24 16:27:51 rpi-test sudo[4673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:27:51 rpi-test sudo[4673]: pam_unix(sudo:session): session closed for user root Jul 24 16:27:51 rpi-test volumio[885]: verbose: New Socket.io Connection to 192.168.50.115 from 192.168.50.118 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:51 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 16:27:51 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:51 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:51 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:51 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:51 rpi-test volumio[885]: info: Listing playlists Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 24 16:27:51 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 24 16:27:53 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:53 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:53 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 24 16:27:53 rpi-test volumio[885]: info: Received Get System Info Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 16:27:53 rpi-test volumio[885]: info: Discovery: Getting this device information Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::volumioGetState Jul 24 16:27:53 rpi-test volumio[885]: info: CorePlayQueue::getTrack 0 Jul 24 16:27:53 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:27:55 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 24 16:27:55 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 24 16:28:01 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 24 16:28:02 rpi-test volumio[885]: info: Enabling plugin fusiondsp Jul 24 16:28:02 rpi-test volumio[885]: info: Loading plugin "fusiondsp"... Jul 24 16:28:04 rpi-test volumio[885]: info: Preparing to generate the ALSA configuration file Jul 24 16:28:04 rpi-test volumio[885]: info: Asound.conf file unchanged, so no further update is needed Jul 24 16:28:04 rpi-test volumio[885]: info: Output device has changed, restarting MPD Jul 24 16:28:05 rpi-test sudo[3360]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:05 rpi-test volumio[885]: info: Output device has changed, restarting Shairport Sync Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:28:05 rpi-test sudo[4702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 24 16:28:05 rpi-test sudo[4702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:05 rpi-test sudo[4704]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jul 24 16:28:05 rpi-test sudo[4704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:05 rpi-test sudo[4702]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:05 rpi-test sudo[4705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 24 16:28:05 rpi-test sudo[4705]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:05 rpi-test sudo[4704]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:05 rpi-test volumio[885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 24 16:28:05 rpi-test volumio[885]: info: Output device has changed, restarting Shairport Sync Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:28:05 rpi-test systemd[1]: Stopping Music Player Daemon... Jul 24 16:28:05 rpi-test volumio[885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 24 16:28:05 rpi-test volumio[885]: info: PLUGIN START: fusiondsp Jul 24 16:28:05 rpi-test systemd[1]: mpd.service: Succeeded. Jul 24 16:28:05 rpi-test systemd[1]: Stopped Music Player Daemon. Jul 24 16:28:05 rpi-test volumio[885]: info: Loading i18n strings for locale nl Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: FusionDsp - mixtype--------------------- Hardware Jul 24 16:28:05 rpi-test volumio[885]: info: Preparing to generate the ALSA configuration file Jul 24 16:28:05 rpi-test systemd[1]: Starting Music Player Daemon... Jul 24 16:28:05 rpi-test volumio[885]: info: Done. Jul 24 16:28:05 rpi-test volumio[885]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 24 16:28:05 rpi-test volumio[885]: info: Reading ALSA contributions from plugins. Jul 24 16:28:05 rpi-test volumio[885]: info: MPD Permissions set Jul 24 16:28:05 rpi-test volumio[885]: info: lib/systemd/system/fusiondsp.service Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:05 rpi-test volumio[885]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 16:28:05 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:28:05 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:28:05 rpi-test sudo[4726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 24 16:28:05 rpi-test sudo[4726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:05 rpi-test sudo[4716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 24 16:28:05 rpi-test sudo[4716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:05 rpi-test sudo[4728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 24 16:28:05 rpi-test sudo[4728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:05 rpi-test sudo[4716]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:05 rpi-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 24 16:28:05 rpi-test systemd[1]: shairport-sync.service: Succeeded. Jul 24 16:28:05 rpi-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:05 rpi-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:05 rpi-test sudo[4726]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:05 rpi-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 24 16:28:05 rpi-test systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 24 16:28:05 rpi-test systemd[1]: shairport-sync.service: Succeeded. Jul 24 16:28:05 rpi-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:05 rpi-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:06 rpi-test sudo[4728]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:06 rpi-test volumio[885]: info: Asound.conf file written Jul 24 16:28:06 rpi-test sudo[4738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 24 16:28:06 rpi-test sudo[4738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:06 rpi-test sudo[4738]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:06 rpi-test volumio[885]: info: Output device has changed, restarting MPD Jul 24 16:28:06 rpi-test volumio[885]: info: Output device has changed, restarting Shairport Sync Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:28:06 rpi-test sudo[4743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 24 16:28:06 rpi-test sudo[4743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:06 rpi-test sudo[4743]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:06 rpi-test sudo[4745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 24 16:28:06 rpi-test sudo[4745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:06 rpi-test systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 24 16:28:06 rpi-test systemd[1]: mpd.service: Succeeded. Jul 24 16:28:06 rpi-test systemd[1]: Stopped Music Player Daemon. Jul 24 16:28:06 rpi-test volumio[885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 24 16:28:06 rpi-test volumio[885]: info: Output device has changed, restarting Shairport Sync Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:28:06 rpi-test systemd[1]: Starting Music Player Daemon... Jul 24 16:28:06 rpi-test volumio[885]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 24 16:28:06 rpi-test volumio[885]: info: Shairport-Sync Started Jul 24 16:28:06 rpi-test volumio[885]: info: Shairport-Sync Started Jul 24 16:28:06 rpi-test volumio[885]: info: MPD Permissions set Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:06 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:07 rpi-test sudo[4751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 24 16:28:07 rpi-test sudo[4751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:07 rpi-test sudo[4751]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:07 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 16:28:07 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:07 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:28:07 rpi-test volumio[885]: info: Starting Shairport Sync Jul 24 16:28:07 rpi-test sudo[4764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 24 16:28:07 rpi-test sudo[4764]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:07 rpi-test sudo[4766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 24 16:28:07 rpi-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 24 16:28:07 rpi-test sudo[4766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:07 rpi-test systemd[1]: shairport-sync.service: Succeeded. Jul 24 16:28:07 rpi-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:07 rpi-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:07 rpi-test sudo[4764]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:07 rpi-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 24 16:28:07 rpi-test systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 24 16:28:07 rpi-test systemd[1]: shairport-sync.service: Succeeded. Jul 24 16:28:07 rpi-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:07 rpi-test volumio[885]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 24 16:28:07 rpi-test volumio[885]: info: camilladsp service started and running in background, instance 1 Jul 24 16:28:07 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 16:28:07 rpi-test volumio[885]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 24 16:28:07 rpi-test volumio[885]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 24 16:28:07 rpi-test volumio[885]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 24 16:28:07 rpi-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 24 16:28:07 rpi-test sudo[4766]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:07 rpi-test volumio[885]: info: FusionDsp loaded Jul 24 16:28:07 rpi-test volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 24 16:28:07 rpi-test sudo[4783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 24 16:28:07 rpi-test volumio[885]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 24 16:28:07 rpi-test volumio[885]: info: Adding Signal Path Element [object Object] Jul 24 16:28:07 rpi-test volumio[885]: info: Adding fusiondspeq DSP Signal Path Element Jul 24 16:28:07 rpi-test sudo[4783]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 16:28:07 rpi-test volumio[885]: info: FusionDsp - ---- installed callbackRead Jul 24 16:28:07 rpi-test volumio[885]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 16:28:07 rpi-test volumio[885]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 24 16:28:07 rpi-test volumio[885]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 24 16:28:07 rpi-test volumio[885]: at onErrorNT (internal/child_process.js:465:16) Jul 24 16:28:07 rpi-test volumio[885]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 24 16:28:07 rpi-test volumio[885]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 24 16:28:07 rpi-test volumio[885]: at listOnTimeout (internal/timers.js:523:9) Jul 24 16:28:07 rpi-test volumio[885]: at processTimers (internal/timers.js:497:7) { Jul 24 16:28:07 rpi-test volumio[885]: errno: -2, Jul 24 16:28:07 rpi-test volumio[885]: code: 'ENOENT', Jul 24 16:28:07 rpi-test volumio[885]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 24 16:28:07 rpi-test volumio[885]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 24 16:28:07 rpi-test volumio[885]: spawnargs: [ Jul 24 16:28:07 rpi-test volumio[885]: '-p', Jul 24 16:28:07 rpi-test volumio[885]: 9876, Jul 24 16:28:07 rpi-test volumio[885]: '-o', Jul 24 16:28:07 rpi-test volumio[885]: '/tmp/camilladsp.log', Jul 24 16:28:07 rpi-test volumio[885]: '-l', Jul 24 16:28:07 rpi-test volumio[885]: 'warn', Jul 24 16:28:07 rpi-test volumio[885]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 24 16:28:07 rpi-test volumio[885]: ] Jul 24 16:28:07 rpi-test volumio[885]: } Jul 24 16:28:07 rpi-test volumio[885]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 16:28:07 rpi-test systemd[1]: Started FusionDsp Daemon. Jul 24 16:28:07 rpi-test sudo[4783]: pam_unix(sudo:session): session closed for user root Jul 24 16:28:08 rpi-test volumio[4788]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 24 16:28:08 rpi-test systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 24 16:28:08 rpi-test systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 24 16:28:09 rpi-test sudo[4797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 16:27 Jul 24 16:28:09 rpi-test sudo[4797]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"