-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sun 2024-04-14 16:02:12 BST. -- Apr 14 16:01:00 redz-volumio volumio[887]: info: Enabling MyMusic plugin bluetooth Apr 14 16:01:00 redz-volumio volumio[887]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Apr 14 16:01:00 redz-volumio volumio[887]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Apr 14 16:01:00 redz-volumio volumio[887]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Apr 14 16:01:00 redz-volumio volumio[887]: error: Could not Enable MyMusic Plugin: Error Apr 14 16:01:00 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:04 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:08 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:12 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:16 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:20 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:21 redz-volumio volumio[887]: info: Selecting previously unselected package g++-8. Apr 14 16:01:21 redz-volumio volumio[887]: info: Preparing to unpack .../12-g++-8_8.3.0-6+rpi1_armhf.deb ... Apr 14 16:01:21 redz-volumio volumio[887]: info: Unpacking g++-8 (8.3.0-6+rpi1) ... Apr 14 16:01:24 redz-volumio volumio[887]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Apr 14 16:01:24 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Apr 14 16:01:24 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:27 redz-volumio volumio[887]: info: Selecting previously unselected package g++. Apr 14 16:01:27 redz-volumio volumio[887]: info: Preparing to unpack .../13-g++_4%3a8.3.0-1+rpi2_armhf.deb ... Apr 14 16:01:27 redz-volumio volumio[887]: info: Unpacking g++ (4:8.3.0-1+rpi2) ... Apr 14 16:01:27 redz-volumio volumio[887]: info: Selecting previously unselected package make. Apr 14 16:01:27 redz-volumio volumio[887]: info: Preparing to unpack .../14-make_4.2.1-1.2_armhf.deb ... Apr 14 16:01:27 redz-volumio volumio[887]: info: Unpacking make (4.2.1-1.2) ... Apr 14 16:01:28 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:28 redz-volumio volumio[887]: info: Selecting previously unselected package patch. Apr 14 16:01:28 redz-volumio volumiologrotate[532]: ls: cannot access '/var/log/samba/log.wb-REDZ': No such file or directory Apr 14 16:01:28 redz-volumio volumiologrotate[532]: ls: cannot access 'VOLUMIO': No such file or directory Apr 14 16:01:28 redz-volumio volumio[887]: info: Preparing to unpack .../15-patch_2.7.6-3+deb10u1_armhf.deb ... Apr 14 16:01:28 redz-volumio volumio[887]: info: Unpacking patch (2.7.6-3+deb10u1) ... Apr 14 16:01:28 redz-volumio volumio[887]: info: Selecting previously unselected package dpkg-dev. Apr 14 16:01:28 redz-volumio volumio[887]: info: Preparing to unpack .../16-dpkg-dev_1.19.8_all.deb ... Apr 14 16:01:29 redz-volumio volumio[887]: info: Unpacking dpkg-dev (1.19.8) ... Apr 14 16:01:32 redz-volumio volumio[887]: info: Selecting previously unselected package build-essential. Apr 14 16:01:32 redz-volumio volumio[887]: info: Preparing to unpack .../17-build-essential_12.6_armhf.deb ... Apr 14 16:01:32 redz-volumio volumio[887]: info: Unpacking build-essential (12.6) ... Apr 14 16:01:32 redz-volumio volumio[887]: info: Selecting previously unselected package dh-python. Apr 14 16:01:32 redz-volumio volumio[887]: info: Preparing to unpack .../18-dh-python_3.20190308_all.deb ... Apr 14 16:01:32 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:32 redz-volumio volumio[887]: info: Unpacking dh-python (3.20190308) ... Apr 14 16:01:33 redz-volumio volumio[887]: info: Selecting previously unselected package libfakeroot:armhf. Apr 14 16:01:33 redz-volumio volumio[887]: info: Preparing to unpack .../19-libfakeroot_1.23-1_armhf.deb ... Apr 14 16:01:33 redz-volumio volumio[887]: info: Unpacking libfakeroot:armhf (1.23-1) ... Apr 14 16:01:34 redz-volumio volumio[887]: info: Selecting previously unselected package fakeroot. Apr 14 16:01:34 redz-volumio volumio[887]: info: Preparing to unpack .../20-fakeroot_1.23-1_armhf.deb ... Apr 14 16:01:34 redz-volumio volumio[887]: info: Unpacking fakeroot (1.23-1) ... Apr 14 16:01:34 redz-volumio volumio[887]: info: Selecting previously unselected package libalgorithm-diff-perl. Apr 14 16:01:34 redz-volumio volumio[887]: info: Preparing to unpack .../21-libalgorithm-diff-perl_1.19.03-2_all.deb ... Apr 14 16:01:34 redz-volumio volumio[887]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Apr 14 16:01:34 redz-volumio volumio[887]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Apr 14 16:01:34 redz-volumio volumio[887]: info: Preparing to unpack .../22-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Apr 14 16:01:34 redz-volumio volumio[887]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Apr 14 16:01:35 redz-volumio volumio[887]: info: Selecting previously unselected package libalgorithm-merge-perl. Apr 14 16:01:35 redz-volumio volumio[887]: info: Preparing to unpack .../23-libalgorithm-merge-perl_0.08-3_all.deb ... Apr 14 16:01:35 redz-volumio volumio[887]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Apr 14 16:01:35 redz-volumio volumio[887]: info: Selecting previously unselected package libexpat1-dev:armhf. Apr 14 16:01:35 redz-volumio volumio[887]: info: Preparing to unpack .../24-libexpat1-dev_2.2.6-2+deb10u7_armhf.deb ... Apr 14 16:01:35 redz-volumio volumio[887]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u7) ... Apr 14 16:01:36 redz-volumio volumio[887]: info: Selecting previously unselected package libpython3.7:armhf. Apr 14 16:01:36 redz-volumio volumio[887]: info: Preparing to unpack .../25-libpython3.7_3.7.3-2+deb10u7_armhf.deb ... Apr 14 16:01:36 redz-volumio volumio[887]: info: Unpacking libpython3.7:armhf (3.7.3-2+deb10u7) ... Apr 14 16:01:36 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:37 redz-volumio volumio[887]: info: Selecting previously unselected package libpython3.7-dev:armhf. Apr 14 16:01:37 redz-volumio volumio[887]: info: Preparing to unpack .../26-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Apr 14 16:01:37 redz-volumio volumio[887]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Apr 14 16:01:43 redz-volumio volumio[887]: info: CoreCommandRouter::volumioPlay Apr 14 16:01:43 redz-volumio volumio[887]: info: CoreStateMachine::play index undefined Apr 14 16:01:43 redz-volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 16:01:43 redz-volumio volumio[887]: info: CorePlayQueue::getTrack 0 Apr 14 16:01:46 redz-volumio volumio[887]: info: CoreCommandRouter::volumioPlay Apr 14 16:01:46 redz-volumio volumio[887]: info: CoreStateMachine::play index undefined Apr 14 16:01:46 redz-volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 16:01:46 redz-volumio volumio[887]: info: CorePlayQueue::getTrack 0 Apr 14 16:01:47 redz-volumio volumio[887]: info: CoreCommandRouter::volumioRandom Apr 14 16:01:47 redz-volumio volumio[887]: info: CoreCommandRouter::writePlayerControls Apr 14 16:01:47 redz-volumio volumio[887]: info: CorePlayQueue::getTrack 0 Apr 14 16:01:47 redz-volumio volumio[887]: info: CoreStateMachine::setRepeat true single false Apr 14 16:01:47 redz-volumio volumio[887]: info: CoreStateMachine::pushState Apr 14 16:01:47 redz-volumio volumio[887]: info: CorePlayQueue::getTrack 0 Apr 14 16:01:47 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:01:47 redz-volumio volumio[887]: info: CoreCommandRouter::volumioPushState Apr 14 16:01:48 redz-volumio volumio[887]: info: CoreCommandRouter::volumioGetQueue Apr 14 16:01:48 redz-volumio volumio[887]: info: CoreStateMachine::getQueue Apr 14 16:01:48 redz-volumio volumio[887]: info: CorePlayQueue::getQueue Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:52 redz-volumio volumio[887]: info: Retrieving Cloud Streaming UI Apr 14 16:01:52 redz-volumio volumio[887]: info: Getting Tidal Cloud Configuration Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:52 redz-volumio volumio[887]: info: Getting Qobuz Cloud Configuration Apr 14 16:01:52 redz-volumio volumio[887]: info: Asking plugin for UI Config Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:52 redz-volumio volumio[887]: info: Getting Spotify Cloud Configuration Apr 14 16:01:52 redz-volumio volumio[887]: info: Asking plugin for UI Config Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:52 redz-volumio volumio[887]: info: Saving Spotify Acccount Apr 14 16:01:52 redz-volumio volumio[887]: info: Got Tidal Cloud Configuration Apr 14 16:01:52 redz-volumio volumio[887]: info: Got it Apr 14 16:01:52 redz-volumio volumio[887]: info: Got it Apr 14 16:01:52 redz-volumio volumio[887]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:52 redz-volumio volumio[887]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 16:01:52 redz-volumio volumio[887]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 16:01:53 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 16:01:53 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 14 16:01:54 redz-volumio volumio[887]: info: Selecting previously unselected package libpython3-dev:armhf. Apr 14 16:01:54 redz-volumio volumio[887]: info: Preparing to unpack .../27-libpython3-dev_3.7.3-1_armhf.deb ... Apr 14 16:01:54 redz-volumio volumio[887]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Apr 14 16:01:54 redz-volumio volumio[887]: info: Selecting previously unselected package python-pip-whl. Apr 14 16:01:54 redz-volumio volumio[887]: info: Preparing to unpack .../28-python-pip-whl_18.1-5+rpt1_all.deb ... Apr 14 16:01:55 redz-volumio volumio[887]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... Apr 14 16:01:55 redz-volumio volumio[887]: info: Selecting previously unselected package python3-multidict. Apr 14 16:01:55 redz-volumio volumio[887]: info: Preparing to unpack .../29-python3-multidict_4.5.2-1_armhf.deb ... Apr 14 16:01:55 redz-volumio volumio[887]: info: Unpacking python3-multidict (4.5.2-1) ... Apr 14 16:01:56 redz-volumio volumio[887]: info: Selecting previously unselected package python3-yarl. Apr 14 16:01:56 redz-volumio volumio[887]: info: Preparing to unpack .../30-python3-yarl_1.3.0-1_armhf.deb ... Apr 14 16:01:56 redz-volumio volumio[887]: info: Unpacking python3-yarl (1.3.0-1) ... Apr 14 16:01:57 redz-volumio volumio[887]: info: Selecting previously unselected package python3-async-timeout. Apr 14 16:01:57 redz-volumio volumio[887]: info: Preparing to unpack .../31-python3-async-timeout_3.0.1-1_all.deb ... Apr 14 16:01:57 redz-volumio volumio[887]: info: Unpacking python3-async-timeout (3.0.1-1) ... Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 16:01:57 redz-volumio volumio[887]: info: Getting Alsa Cards List without I2S DAC Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 16:01:57 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 16:01:57 redz-volumio volumio[887]: info: Selecting previously unselected package python3-attr. Apr 14 16:01:57 redz-volumio volumio[887]: info: Preparing to unpack .../32-python3-attr_18.2.0-1_all.deb ... Apr 14 16:01:57 redz-volumio volumio[887]: info: Unpacking python3-attr (18.2.0-1) ... Apr 14 16:01:57 redz-volumio volumio[887]: info: Selecting previously unselected package python3-aiohttp. Apr 14 16:01:57 redz-volumio volumio[887]: info: Preparing to unpack .../33-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Apr 14 16:01:58 redz-volumio volumio[887]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Apr 14 16:01:59 redz-volumio volumio[887]: info: Selecting previously unselected package python3-asn1crypto. Apr 14 16:01:59 redz-volumio volumio[887]: info: Preparing to unpack .../34-python3-asn1crypto_0.24.0-1_all.deb ... Apr 14 16:01:59 redz-volumio volumio[887]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Apr 14 16:02:00 redz-volumio volumio[887]: info: Selecting previously unselected package python3-cffi-backend. Apr 14 16:02:00 redz-volumio volumio[887]: info: Preparing to unpack .../35-python3-cffi-backend_1.12.2-1_armhf.deb ... Apr 14 16:02:00 redz-volumio volumio[887]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Apr 14 16:02:01 redz-volumio volumio[887]: info: Selecting previously unselected package python3-crypto. Apr 14 16:02:01 redz-volumio volumio[887]: info: Preparing to unpack .../36-python3-crypto_2.6.1-9+b1_armhf.deb ... Apr 14 16:02:01 redz-volumio volumio[887]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Apr 14 16:02:04 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 16:02:04 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 16:02:05 redz-volumio volumio[887]: info: Selecting previously unselected package python3-cryptography. Apr 14 16:02:05 redz-volumio volumio[887]: info: Preparing to unpack .../37-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Apr 14 16:02:05 redz-volumio volumio[887]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Apr 14 16:02:07 redz-volumio volumio[887]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.15 UA: okhttp/4.9.2 Total Clients: 9 Apr 14 16:02:07 redz-volumio volumio[887]: info: CoreCommandRouter::volumioGetState Apr 14 16:02:07 redz-volumio volumio[887]: info: CorePlayQueue::getTrack 0 Apr 14 16:02:07 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 16:02:07 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 16:02:08 redz-volumio volumio[887]: info: Selecting previously unselected package python3-dbus. Apr 14 16:02:08 redz-volumio volumio[887]: info: Preparing to unpack .../38-python3-dbus_1.2.8-3_armhf.deb ... Apr 14 16:02:08 redz-volumio volumio[887]: info: Unpacking python3-dbus (1.2.8-3) ... Apr 14 16:02:09 redz-volumio volumio[887]: info: Selecting previously unselected package python3.7-dev. Apr 14 16:02:09 redz-volumio volumio[887]: info: Preparing to unpack .../39-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Apr 14 16:02:09 redz-volumio volumio[887]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Apr 14 16:02:09 redz-volumio volumio[887]: info: Enabling plugin fusiondsp Apr 14 16:02:09 redz-volumio volumio[887]: info: Loading plugin "fusiondsp"... Apr 14 16:02:10 redz-volumio volumio[887]: info: Preparing to generate the ALSA configuration file Apr 14 16:02:10 redz-volumio volumio[887]: info: Selecting previously unselected package python3-dev. Apr 14 16:02:10 redz-volumio volumio[887]: info: Preparing to unpack .../40-python3-dev_3.7.3-1_armhf.deb ... Apr 14 16:02:10 redz-volumio volumio[887]: info: Unpacking python3-dev (3.7.3-1) ... Apr 14 16:02:10 redz-volumio volumio[887]: info: Asound.conf file unchanged, so no further update is needed Apr 14 16:02:10 redz-volumio volumio[887]: info: Output device has changed, restarting MPD Apr 14 16:02:10 redz-volumio sudo[8432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 16:02:10 redz-volumio sudo[8432]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:10 redz-volumio volumio[887]: info: Output device has changed, restarting Shairport Sync Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:02:10 redz-volumio sudo[8432]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:10 redz-volumio sudo[8435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 16:02:10 redz-volumio sudo[8435]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:10 redz-volumio systemd[1]: Stopping Music Player Daemon... Apr 14 16:02:10 redz-volumio volumio[887]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 16:02:10 redz-volumio systemd[1]: mpd.service: Succeeded. Apr 14 16:02:10 redz-volumio volumio[887]: info: PLUGIN START: fusiondsp Apr 14 16:02:10 redz-volumio systemd[1]: Stopped Music Player Daemon. Apr 14 16:02:10 redz-volumio volumio[887]: info: Loading i18n strings for locale en Apr 14 16:02:10 redz-volumio volumio[887]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: FusionDsp - mixtype--------------------- Hardware Apr 14 16:02:10 redz-volumio volumio[887]: info: Preparing to generate the ALSA configuration file Apr 14 16:02:10 redz-volumio systemd[1]: Starting Music Player Daemon... Apr 14 16:02:10 redz-volumio volumio[887]: info: Done. Apr 14 16:02:10 redz-volumio volumio[887]: info: MPD Permissions set Apr 14 16:02:10 redz-volumio volumio[887]: info: Selecting previously unselected package python3-entrypoints. Apr 14 16:02:10 redz-volumio volumio[887]: info: Preparing to unpack .../41-python3-entrypoints_0.3-1_all.deb ... Apr 14 16:02:10 redz-volumio volumio[887]: info: Unpacking python3-entrypoints (0.3-1) ... Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: Asound.conf file unchanged, so no further update is needed Apr 14 16:02:10 redz-volumio volumio[887]: info: Output device has changed, restarting MPD Apr 14 16:02:10 redz-volumio volumio[887]: info: Output device has changed, restarting Shairport Sync Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:02:10 redz-volumio sudo[8456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 16:02:10 redz-volumio sudo[8456]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:10 redz-volumio sudo[8456]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:10 redz-volumio sudo[8458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 16:02:10 redz-volumio sudo[8458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:10 redz-volumio volumio[887]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 16:02:10 redz-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 16:02:10 redz-volumio systemd[1]: mpd.service: Succeeded. Apr 14 16:02:10 redz-volumio systemd[1]: Stopped Music Player Daemon. Apr 14 16:02:10 redz-volumio systemd[1]: Starting Music Player Daemon... Apr 14 16:02:10 redz-volumio volumio[887]: info: MPD Permissions set Apr 14 16:02:10 redz-volumio volumio[887]: info: Starting Shairport Sync Apr 14 16:02:10 redz-volumio volumio[887]: info: Selecting previously unselected package python3-gi. Apr 14 16:02:10 redz-volumio volumio[887]: info: Preparing to unpack .../42-python3-gi_3.30.4-1_armhf.deb ... Apr 14 16:02:10 redz-volumio sudo[8469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 16:02:10 redz-volumio sudo[8469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:10 redz-volumio volumio[887]: info: Unpacking python3-gi (3.30.4-1) ... Apr 14 16:02:10 redz-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:10 redz-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 16:02:10 redz-volumio volumio[887]: info: Starting Shairport Sync Apr 14 16:02:10 redz-volumio sudo[8469]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:10 redz-volumio volumio[887]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:02:10 redz-volumio volumio[887]: info: Shairport-Sync Started Apr 14 16:02:10 redz-volumio sudo[8482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 16:02:10 redz-volumio sudo[8482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:10 redz-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 16:02:10 redz-volumio systemd[1]: shairport-sync.service: Succeeded. Apr 14 16:02:10 redz-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 16:02:10 redz-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 16:02:10 redz-volumio sudo[8482]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:11 redz-volumio volumio[887]: info: Shairport-Sync Started Apr 14 16:02:11 redz-volumio mpd[8464]: Apr 14 16:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 16:02:11 redz-volumio systemd[1]: Started Music Player Daemon. Apr 14 16:02:11 redz-volumio sudo[8458]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:11 redz-volumio sudo[8435]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:11 redz-volumio volumio[887]: error: MPD error: The expression evaluated to a falsy value: Apr 14 16:02:11 redz-volumio volumio[887]: assert.ok(self.idling) Apr 14 16:02:11 redz-volumio volumio[887]: error: The expression evaluated to a falsy value: Apr 14 16:02:11 redz-volumio volumio[887]: assert.ok(self.idling) Apr 14 16:02:11 redz-volumio volumio[887]: error: updateQueue error: null Apr 14 16:02:12 redz-volumio volumio[887]: info: Selecting previously unselected package python3-secretstorage. Apr 14 16:02:12 redz-volumio volumio[887]: info: Preparing to unpack .../43-python3-secretstorage_2.3.1-2_all.deb ... Apr 14 16:02:12 redz-volumio volumio[887]: info: Unpacking python3-secretstorage (2.3.1-2) ... Apr 14 16:02:12 redz-volumio volumio[887]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 14 16:02:12 redz-volumio volumio[887]: info: camilladsp service started and running in background, instance 1 Apr 14 16:02:12 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:02:12 redz-volumio volumio[887]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 14 16:02:12 redz-volumio volumio[887]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 14 16:02:12 redz-volumio volumio[887]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 14 16:02:12 redz-volumio volumio[887]: info: FusionDsp loaded Apr 14 16:02:12 redz-volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:02:12 redz-volumio sudo[8510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 14 16:02:12 redz-volumio sudo[8510]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:02:12 redz-volumio volumio[887]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 14 16:02:12 redz-volumio volumio[887]: info: Adding Signal Path Element [object Object] Apr 14 16:02:12 redz-volumio volumio[887]: info: Adding fusiondspeq DSP Signal Path Element Apr 14 16:02:12 redz-volumio volumio[887]: info: FusionDsp - ---- installed callbackRead Apr 14 16:02:12 redz-volumio volumio[887]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:02:12 redz-volumio volumio[887]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 14 16:02:12 redz-volumio volumio[887]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 14 16:02:12 redz-volumio volumio[887]: at onErrorNT (internal/child_process.js:465:16) Apr 14 16:02:12 redz-volumio volumio[887]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 14 16:02:12 redz-volumio volumio[887]: errno: -2, Apr 14 16:02:12 redz-volumio volumio[887]: code: 'ENOENT', Apr 14 16:02:12 redz-volumio volumio[887]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 14 16:02:12 redz-volumio volumio[887]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 14 16:02:12 redz-volumio volumio[887]: spawnargs: [ Apr 14 16:02:12 redz-volumio volumio[887]: '-p', Apr 14 16:02:12 redz-volumio volumio[887]: 9876, Apr 14 16:02:12 redz-volumio volumio[887]: '-o', Apr 14 16:02:12 redz-volumio volumio[887]: '/tmp/camilladsp.log', Apr 14 16:02:12 redz-volumio volumio[887]: '-l', Apr 14 16:02:12 redz-volumio volumio[887]: 'warn', Apr 14 16:02:12 redz-volumio volumio[887]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 14 16:02:12 redz-volumio volumio[887]: ] Apr 14 16:02:12 redz-volumio volumio[887]: } Apr 14 16:02:12 redz-volumio volumio[887]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:02:12 redz-volumio sudo[8510]: pam_unix(sudo:session): session closed for user root Apr 14 16:02:12 redz-volumio sudo[8530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 16:01 Apr 14 16:02:12 redz-volumio sudo[8530]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"