-- Logs begin at Wed 2025-06-18 17:41:45 +07, end at Wed 2025-06-18 19:25:18 +07. -- Jun 18 19:24:00 volumio volumio[4392]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jun 18 19:24:00 volumio volumio[4392]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-3iiocjun/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jun 18 19:24:00 volumio volumio[4392]: info: Successfully built camilladsp Jun 18 19:24:05 volumio volumio[4392]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jun 18 19:24:05 volumio volumio[4392]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-d39b5z1q Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Jun 18 19:24:07 volumio volumio[4392]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Jun 18 19:24:08 volumio volumio[4392]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Jun 18 19:24:08 volumio volumio[4392]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Jun 18 19:24:08 volumio volumio[4392]: info: Building wheels for collected packages: camilladsp-plot Jun 18 19:24:08 volumio volumio[4392]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jun 18 19:24:09 volumio volumio[4392]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jun 18 19:24:09 volumio volumio[4392]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-ujksq071/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jun 18 19:24:09 volumio volumio[4392]: info: Successfully built camilladsp-plot Jun 18 19:24:09 volumio volumio[4392]: info: copying hw detection script Jun 18 19:24:09 volumio volumio[4392]: info: Detected cpu architecture as x86_64 Jun 18 19:24:17 volumio sudo[21746]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jun 18 19:24:17 volumio sudo[21746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:17 volumio volumio[4392]: info: Reading package lists... Jun 18 19:24:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 19:24:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 19:24:18 volumio volumio[4392]: info: Discovery: Getting this device information Jun 18 19:24:18 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:24:18 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:24:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:24:18 volumio volumio[4392]: info: Building dependency tree... Jun 18 19:24:18 volumio volumio[4392]: info: Reading state information... Jun 18 19:24:19 volumio volumio[4392]: info: drc is already the newest version (3.2.2~dfsg0-2). Jun 18 19:24:19 volumio sudo[21746]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:19 volumio sudo[21299]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:19 volumio volumio[4392]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jun 18 19:24:19 volumio volumio[4392]: info: Install script completed Jun 18 19:24:19 volumio volumio[4392]: info: Adding reference to registry Jun 18 19:24:19 volumio volumio[4392]: info: Done installing plugin. Jun 18 19:24:19 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 18 19:24:19 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 18 19:24:19 volumio volumio[4392]: info: Folder /tmp/plugins removed Jun 18 19:24:19 volumio volumio[4392]: info: Folder /tmp/downloaded_plugin.zip removed Jun 18 19:24:19 volumio volumio[4392]: Plugin install end detected on script Jun 18 19:24:19 volumio volumio[4392]: info: Folder /data/temp removed Jun 18 19:24:21 volumio volumio[4392]: info: Enabling plugin fusiondsp Jun 18 19:24:21 volumio volumio[4392]: info: Loading plugin "fusiondsp"... Jun 18 19:24:21 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:24:21 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:24:21 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:24:21 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:24:21 volumio volumio[4392]: info: Asound.conf file written Jun 18 19:24:22 volumio sudo[21755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 18 19:24:22 volumio sudo[21755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio sudo[21755]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:22 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:24:22 volumio sudo[21762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:24:22 volumio sudo[21762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio sudo[21762]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:22 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:22 volumio sudo[21765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:24:22 volumio sudo[21765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 19:24:22 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:24:22 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:24:22 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:24:22 volumio volumio[4392]: info: PLUGIN START: fusiondsp Jun 18 19:24:22 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:24:22 volumio volumio[4392]: info: Loading i18n strings for locale vi Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio volumio[4392]: info: FusionDsp - mixtype--------------------- Software Jun 18 19:24:22 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:24:22 volumio volumio[4392]: info: Done. Jun 18 19:24:22 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:24:22 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:24:22 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:24:22 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:24:22 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio sudo[21770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:24:22 volumio sudo[21770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio sudo[21770]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 19:24:22 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:24:22 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:24:22 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:24:22 volumio sudo[21782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:24:22 volumio sudo[21782]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:24:22 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:24:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:22 volumio sudo[21785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:24:22 volumio sudo[21785]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:22 volumio sudo[21785]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:22 volumio sudo[21782]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:22 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:22 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:22 volumio sudo[21789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:24:22 volumio sudo[21789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:24:22 volumio volumio[4392]: info: FusionDsp - Jun 18 19:24:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 18 19:24:22 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:24:22 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:24:22 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:24:22 volumio sudo[21796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:24:22 volumio sudo[21796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:22 volumio sudo[21796]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:23 volumio volumio[4392]: info: FusionDsp - undefined Jun 18 19:24:23 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:24:23 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:24:23 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:23 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:23 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:23 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:24:23 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:23 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:24:23 volumio sudo[21806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:24:23 volumio sudo[21806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:24:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:24:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:23 volumio sudo[21806]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:23 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:24:24 volumio mpd[21798]: Jun 18 19:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 19:24:24 volumio systemd[1]: Started Music Player Daemon. Jun 18 19:24:24 volumio sudo[21789]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:24 volumio sudo[21765]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:24 volumio volumio[4392]: error: MPD error: The expression evaluated to a falsy value: Jun 18 19:24:24 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:24:24 volumio volumio[4392]: error: The expression evaluated to a falsy value: Jun 18 19:24:24 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:24:24 volumio volumio[4392]: error: updateQueue error: null Jun 18 19:24:24 volumio volumio[4392]: info: camilladsp service started and running in background, instance 1 Jun 18 19:24:24 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:24 volumio volumio[4392]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jun 18 19:24:24 volumio volumio[4392]: info: FusionDsp loaded Jun 18 19:24:24 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:24:24 volumio sudo[21841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 18 19:24:24 volumio sudo[21841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:24 volumio volumio[4392]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 18 19:24:24 volumio volumio[4392]: info: Adding Signal Path Element [object Object] Jun 18 19:24:24 volumio volumio[4392]: info: Adding fusiondspeq DSP Signal Path Element Jun 18 19:24:24 volumio volumio[4392]: info: FusionDsp - ---- installed callbackRead Jun 18 19:24:24 volumio volumio[4392]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 18 19:24:24 volumio volumio[4392]: error: FusionDsp - WebSocket error: [object Object] Jun 18 19:24:24 volumio systemd[1]: Started FusionDsp Daemon. Jun 18 19:24:24 volumio sudo[21841]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:24 volumio volumio[4392]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 18 19:24:25 volumio volumio[4392]: 0+0 records in Jun 18 19:24:25 volumio volumio[4392]: 0+0 records out Jun 18 19:24:25 volumio volumio[4392]: 0 bytes copied, 0.000294212 s, 0.0 kB/s Jun 18 19:24:31 volumio volumio[4392]: info: Enabling plugin fusiondsp Jun 18 19:24:31 volumio volumio[4392]: info: Loading plugin "fusiondsp"... Jun 18 19:24:31 volumio volumio[4392]: info: Applying required configuration parameters for plugin fusiondsp Jun 18 19:24:31 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:24:31 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:24:31 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:24:31 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:24:31 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:24:31 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:24:31 volumio sudo[21855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:24:31 volumio sudo[21855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:31 volumio sudo[21855]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:31 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:24:31 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:31 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:31 volumio sudo[21858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:24:31 volumio sudo[21858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:32 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 19:24:32 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:24:32 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:24:32 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:24:32 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:24:32 volumio volumio[4392]: info: PLUGIN START: fusiondsp Jun 18 19:24:32 volumio volumio[4392]: info: Loading i18n strings for locale vi Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: FusionDsp - mixtype--------------------- Software Jun 18 19:24:32 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:24:32 volumio volumio[4392]: info: Done. Jun 18 19:24:32 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:24:32 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:24:32 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:24:32 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:24:32 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio sudo[21863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:24:32 volumio sudo[21863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:24:32 volumio sudo[21863]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:32 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:32 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:24:32 volumio volumio[4392]: info: FusionDsp - Jun 18 19:24:32 volumio sudo[21873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:24:32 volumio sudo[21873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:24:32 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:24:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:32 volumio sudo[21873]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:32 volumio volumio[4392]: info: FusionDsp - undefined Jun 18 19:24:32 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:24:32 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:24:32 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:24:32 volumio sudo[21880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:24:33 volumio sudo[21880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:33 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:33 volumio sudo[21880]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:33 volumio sudo[21883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:24:33 volumio sudo[21883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 18 19:24:33 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:24:33 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:24:33 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:24:33 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:24:33 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:24:33 volumio sudo[21888]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:24:33 volumio sudo[21888]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:33 volumio sudo[21888]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:24:33 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:33 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:24:33 volumio sudo[21898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:24:33 volumio sudo[21898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:24:33 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:24:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:33 volumio sudo[21898]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:33 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:24:34 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:24:34 volumio volumio[4392]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 18 19:24:34 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:24:34 volumio volumio[4392]: info: camilladsp service started and running in background, instance 2 Jun 18 19:24:34 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:34 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:24:34 volumio volumio[4392]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 18 19:24:34 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp loaded Jun 18 19:24:34 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 18 19:24:34 volumio volumio[4392]: info: Adding Signal Path Element [object Object] Jun 18 19:24:34 volumio volumio[4392]: info: Updating fusiondspeq DSP Signal Path Element Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp - ---- installed callbackRead Jun 18 19:24:34 volumio sudo[21933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 18 19:24:34 volumio sudo[21933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:24:34 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:24:34 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 18 19:24:34 volumio sudo[21933]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:34 volumio volumio[4392]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 18 19:24:34 volumio volumio[4392]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 18 19:24:34 volumio mpd[21890]: Jun 18 19:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 19:24:34 volumio systemd[1]: Started Music Player Daemon. Jun 18 19:24:34 volumio sudo[21858]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:34 volumio sudo[21883]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:34 volumio volumio[4392]: error: MPD error: The expression evaluated to a falsy value: Jun 18 19:24:34 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:24:34 volumio volumio[4392]: error: The expression evaluated to a falsy value: Jun 18 19:24:34 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:24:34 volumio volumio[4392]: error: updateQueue error: null Jun 18 19:24:42 volumio volumio[4392]: info: Enabling plugin fusiondsp Jun 18 19:24:42 volumio volumio[4392]: info: Loading plugin "fusiondsp"... Jun 18 19:24:42 volumio volumio[4392]: info: Applying required configuration parameters for plugin fusiondsp Jun 18 19:24:42 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:24:42 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:24:42 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:24:42 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:24:42 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:24:42 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:24:42 volumio sudo[22740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:24:42 volumio sudo[22740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:42 volumio sudo[22740]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:42 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:42 volumio sudo[22743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:24:42 volumio sudo[22743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:42 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 19:24:42 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:24:42 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:24:42 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:24:42 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:24:42 volumio volumio[4392]: info: PLUGIN START: fusiondsp Jun 18 19:24:42 volumio volumio[4392]: info: Loading i18n strings for locale vi Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: FusionDsp - mixtype--------------------- Software Jun 18 19:24:42 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:24:42 volumio volumio[4392]: info: Done. Jun 18 19:24:42 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:24:42 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:24:42 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:24:42 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:24:42 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio sudo[22748]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio sudo[22748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:42 volumio sudo[22748]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:24:42 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:42 volumio volumio[4392]: info: FusionDsp - Jun 18 19:24:43 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:24:43 volumio volumio[4392]: info: FusionDsp - undefined Jun 18 19:24:43 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:24:43 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:24:43 volumio sudo[22772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:24:43 volumio sudo[22772]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:24:43 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:24:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:43 volumio sudo[22772]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:43 volumio sudo[22775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:24:43 volumio sudo[22775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:43 volumio sudo[22775]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:43 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:43 volumio sudo[22781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:24:43 volumio sudo[22781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:43 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 18 19:24:43 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:24:43 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:24:43 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:24:43 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:24:43 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:24:43 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio sudo[22786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:24:43 volumio sudo[22786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:43 volumio sudo[22786]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:24:43 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:43 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:24:43 volumio sudo[22810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:24:43 volumio sudo[22810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:24:43 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:24:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:24:43 volumio sudo[22810]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:43 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:24:44 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:24:44 volumio volumio[4392]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 18 19:24:44 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:24:44 volumio volumio[4392]: info: camilladsp service started and running in background, instance 3 Jun 18 19:24:44 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:24:44 volumio mpd[22796]: Jun 18 19:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 19:24:44 volumio systemd[1]: Started Music Player Daemon. Jun 18 19:24:44 volumio sudo[22781]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:44 volumio sudo[22743]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:44 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:24:44 volumio volumio[4392]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 18 19:24:44 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp loaded Jun 18 19:24:44 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 18 19:24:44 volumio volumio[4392]: info: Adding Signal Path Element [object Object] Jun 18 19:24:44 volumio volumio[4392]: info: Updating fusiondspeq DSP Signal Path Element Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp - ---- installed callbackRead Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:24:44 volumio sudo[22917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 18 19:24:44 volumio sudo[22917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:24:44 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:24:44 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:24:44 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:24:44 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:24:44 volumio sudo[22917]: pam_unix(sudo:session): session closed for user root Jun 18 19:24:45 volumio volumio[4392]: error: MPD error: The expression evaluated to a falsy value: Jun 18 19:24:45 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:24:45 volumio volumio[4392]: error: The expression evaluated to a falsy value: Jun 18 19:24:45 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:24:45 volumio volumio[4392]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 18 19:24:45 volumio volumio[4392]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 18 19:24:45 volumio volumio[4392]: error: updateQueue error: null Jun 18 19:24:48 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 19:24:48 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 19:24:48 volumio volumio[4392]: info: Discovery: Getting this device information Jun 18 19:24:48 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:24:48 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:24:48 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:25:00 volumio volumio[4392]: info: Enabling plugin fusiondsp Jun 18 19:25:00 volumio volumio[4392]: info: Loading plugin "fusiondsp"... Jun 18 19:25:00 volumio volumio[4392]: info: Applying required configuration parameters for plugin fusiondsp Jun 18 19:25:00 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:25:00 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:25:00 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:25:00 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:25:00 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:25:00 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:25:00 volumio sudo[25354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:25:00 volumio sudo[25354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:00 volumio sudo[25354]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:00 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:00 volumio sudo[25357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:25:00 volumio sudo[25357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:00 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 19:25:00 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:25:00 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:25:00 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:25:00 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:25:00 volumio volumio[4392]: info: PLUGIN START: fusiondsp Jun 18 19:25:00 volumio volumio[4392]: info: Loading i18n strings for locale vi Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:00 volumio volumio[4392]: info: FusionDsp - mixtype--------------------- Software Jun 18 19:25:00 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:25:00 volumio volumio[4392]: info: Done. Jun 18 19:25:00 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:25:00 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:25:00 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:25:00 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:25:00 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:25:00 volumio sudo[25362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:25:00 volumio volumio[4392]: info: Enabling plugin fusiondsp Jun 18 19:25:00 volumio sudo[25362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 19:25:00 volumio volumio[4392]: info: Loading plugin "fusiondsp"... Jun 18 19:25:00 volumio volumio[4392]: info: Applying required configuration parameters for plugin fusiondsp Jun 18 19:25:00 volumio sudo[25362]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:00 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: FusionDsp - Jun 18 19:25:01 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:25:01 volumio volumio[4392]: info: FusionDsp - undefined Jun 18 19:25:01 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:25:01 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:25:01 volumio sudo[25400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:25:01 volumio sudo[25400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:25:01 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:25:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:01 volumio sudo[25400]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:01 volumio sudo[25404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:25:01 volumio sudo[25404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:01 volumio sudo[25404]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:01 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:01 volumio sudo[25409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:25:01 volumio sudo[25409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 18 19:25:01 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:25:01 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:25:01 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:25:01 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:25:01 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:25:01 volumio sudo[25414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:25:01 volumio sudo[25414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:01 volumio sudo[25414]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:01 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:25:01 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:25:01 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:25:01 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:25:01 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:01 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:25:02 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:25:02 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:25:02 volumio sudo[25459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:25:02 volumio sudo[25459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:02 volumio sudo[25461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:25:02 volumio sudo[25461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:02 volumio sudo[25461]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:25:02 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:25:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:02 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:02 volumio sudo[25459]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:02 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:25:02 volumio volumio[4392]: info: PLUGIN START: fusiondsp Jun 18 19:25:02 volumio sudo[25466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:25:02 volumio sudo[25466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:02 volumio volumio[4392]: info: Loading i18n strings for locale vi Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: FusionDsp - mixtype--------------------- Software Jun 18 19:25:02 volumio volumio[4392]: info: Preparing to generate the ALSA configuration file Jun 18 19:25:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 18 19:25:02 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:25:02 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:25:02 volumio volumio[4392]: info: Done. Jun 18 19:25:02 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:25:02 volumio volumio[4392]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 19:25:02 volumio volumio[4392]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 18 19:25:02 volumio volumio[4392]: info: Reading ALSA contributions from plugins. Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 19:25:02 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:25:02 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:25:02 volumio sudo[25473]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:25:02 volumio sudo[25473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:02 volumio sudo[25473]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:25:02 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:02 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:25:02 volumio volumio[4392]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 18 19:25:02 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:25:03 volumio volumio[4392]: info: camilladsp service started and running in background, instance 4 Jun 18 19:25:03 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:03 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:25:03 volumio volumio[4392]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 18 19:25:03 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp loaded Jun 18 19:25:03 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 18 19:25:03 volumio volumio[4392]: info: Adding Signal Path Element [object Object] Jun 18 19:25:03 volumio volumio[4392]: info: Updating fusiondspeq DSP Signal Path Element Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - ---- installed callbackRead Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 18 19:25:03 volumio sudo[25519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - Jun 18 19:25:03 volumio sudo[25519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:03 volumio sudo[25519]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:03 volumio volumio[4392]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:03 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:03 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:03 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:03 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:03 volumio volumio[4392]: info: FusionDsp - undefined Jun 18 19:25:03 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:25:03 volumio volumio[4392]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 18 19:25:03 volumio sudo[25546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:25:03 volumio sudo[25546]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:03 volumio mpd[25483]: Jun 18 19:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 19:25:03 volumio systemd[1]: Started Music Player Daemon. Jun 18 19:25:03 volumio sudo[25357]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:03 volumio sudo[25409]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:03 volumio sudo[25466]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:25:03 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:25:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:04 volumio volumio[4392]: info: Asound.conf file unchanged, so no further update is needed Jun 18 19:25:04 volumio volumio[4392]: info: Output device has changed, restarting MPD Jun 18 19:25:04 volumio sudo[25546]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:04 volumio sudo[25568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 19:25:04 volumio sudo[25568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:04 volumio sudo[25568]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:04 volumio volumio[4392]: info: Output device has changed, restarting Shairport Sync Jun 18 19:25:04 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:04 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:04 volumio sudo[25583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 19:25:04 volumio sudo[25583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:04 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 19:25:04 volumio systemd[1]: mpd.service: Succeeded. Jun 18 19:25:04 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 19:25:04 volumio volumio[4392]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 18 19:25:04 volumio systemd[1]: Starting Music Player Daemon... Jun 18 19:25:04 volumio volumio[4392]: error: [ytcr] Error connecting MPD: Jun 18 19:25:04 volumio volumio[4392]: (Error) write EPIPE Jun 18 19:25:04 volumio volumio[4392]: Error: write EPIPE Jun 18 19:25:04 volumio volumio[4392]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Jun 18 19:25:04 volumio volumio[4392]: at writeGeneric (internal/stream_base_commons.js:147:3) Jun 18 19:25:04 volumio volumio[4392]: at Socket._writeGeneric (net.js:798:11) Jun 18 19:25:04 volumio volumio[4392]: at Socket._write (net.js:810:8) Jun 18 19:25:04 volumio volumio[4392]: at writeOrBuffer (internal/streams/writable.js:358:12) Jun 18 19:25:04 volumio volumio[4392]: at Socket.Writable.write (internal/streams/writable.js:303:10) Jun 18 19:25:04 volumio volumio[4392]: at Timeout._onTimeout (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:276:18) Jun 18 19:25:04 volumio volumio[4392]: at listOnTimeout (internal/timers.js:557:17) Jun 18 19:25:04 volumio volumio[4392]: at processTimers (internal/timers.js:500:7) Jun 18 19:25:04 volumio volumio[4392]: Retrying in 5 seconds... Jun 18 19:25:04 volumio volumio[4392]: error: MPD error: Error: write EPIPE Jun 18 19:25:04 volumio volumio[4392]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jun 18 19:25:04 volumio volumio[4392]: error: MPD error: The expression evaluated to a falsy value: Jun 18 19:25:04 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:25:04 volumio volumio[4392]: error: The expression evaluated to a falsy value: Jun 18 19:25:04 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:25:04 volumio volumio[4392]: error: MPD error: Error: write EPIPE Jun 18 19:25:04 volumio volumio[4392]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jun 18 19:25:04 volumio sudo[25590]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 19:25:04 volumio sudo[25590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:04 volumio volumio[4392]: error: MPD error: The expression evaluated to a falsy value: Jun 18 19:25:04 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:25:04 volumio volumio[4392]: error: The expression evaluated to a falsy value: Jun 18 19:25:04 volumio volumio[4392]: assert.ok(self.idling) Jun 18 19:25:04 volumio volumio[4392]: error: MPD error: Error: write EPIPE Jun 18 19:25:04 volumio volumio[4392]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jun 18 19:25:04 volumio sudo[25590]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:04 volumio volumio[4392]: info: MPD Permissions set Jun 18 19:25:04 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:25:04 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:25:04 volumio volumio[4392]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 18 19:25:04 volumio volumio[4392]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 18 19:25:04 volumio volumio[4392]: info: camilladsp service started and running in background, instance 5 Jun 18 19:25:04 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:04 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:25:04 volumio volumio[4392]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 18 19:25:04 volumio volumio[4392]: cannot open device 'volumioHw': Device or resource busy Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp loaded Jun 18 19:25:04 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 18 19:25:04 volumio volumio[4392]: info: Adding Signal Path Element [object Object] Jun 18 19:25:04 volumio volumio[4392]: info: Updating fusiondspeq DSP Signal Path Element Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - ---- installed callbackRead Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 18 19:25:04 volumio sudo[25630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 18 19:25:04 volumio sudo[25630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:04 volumio sudo[25630]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:04 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:04 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:04 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:04 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:05 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:05 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:05 volumio volumio[4392]: info: FusionDsp - ---- read samplerate, raw: Jun 18 19:25:05 volumio volumio[4392]: error: FusionDsp - invalid sample rate Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 19:25:05 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 19:25:05 volumio volumio[4392]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 18 19:25:05 volumio volumio[4392]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 18 19:25:05 volumio volumio[4392]: info: Starting Shairport Sync Jun 18 19:25:05 volumio mpd[25599]: Jun 18 19:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 19:25:05 volumio systemd[1]: Started Music Player Daemon. Jun 18 19:25:05 volumio sudo[25583]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:05 volumio sudo[25705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 19:25:05 volumio sudo[25705]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 19:25:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 19:25:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 19:25:05 volumio sudo[25705]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:05 volumio volumio[4392]: info: Shairport-Sync Started Jun 18 19:25:06 volumio volumio[4392]: error: updateQueue error: null Jun 18 19:25:17 volumio volumio[4392]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 18 19:25:17 volumio volumio[4392]: info: FusionDsp - Stopping FusionDsp service Jun 18 19:25:17 volumio volumio[4392]: info: camilladsp stopping service pid 27443... Jun 18 19:25:17 volumio volumio[4392]: info: camilladsp service terminated, instance 5 Jun 18 19:25:17 volumio sudo[27453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jun 18 19:25:17 volumio sudo[27453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 19:25:17 volumio systemd[1]: Stopping FusionDsp Daemon... Jun 18 19:25:17 volumio volumio[21843]: Backend configuration: Jun 18 19:25:17 volumio volumio[21843]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 18 19:25:17 volumio volumio[21843]: active_config_txt: null Jun 18 19:25:17 volumio volumio[21843]: camilla_host: 127.0.0.1 Jun 18 19:25:17 volumio volumio[21843]: camilla_port: 9876 Jun 18 19:25:17 volumio volumio[21843]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jun 18 19:25:17 volumio volumio[21843]: config_dir: /data/configuration/audio_interface/fusiondsp Jun 18 19:25:17 volumio volumio[21843]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 18 19:25:17 volumio volumio[21843]: log_file: /tmp/camilladsp.log Jun 18 19:25:17 volumio volumio[21843]: on_get_active_config: null Jun 18 19:25:17 volumio volumio[21843]: on_set_active_config: null Jun 18 19:25:17 volumio volumio[21843]: port: 5011 Jun 18 19:25:17 volumio volumio[21843]: supported_capture_types: null Jun 18 19:25:17 volumio volumio[21843]: supported_playback_types: null Jun 18 19:25:17 volumio volumio[21843]: update_config_symlink: false Jun 18 19:25:17 volumio volumio[21843]: update_config_txt: false Jun 18 19:25:17 volumio volumio[21843]: ======== Running on http://0.0.0.0:5011 ======== Jun 18 19:25:17 volumio volumio[21843]: (Press CTRL+C to quit) Jun 18 19:25:17 volumio systemd[1]: fusiondsp.service: Succeeded. Jun 18 19:25:17 volumio systemd[1]: Stopped FusionDsp Daemon. Jun 18 19:25:17 volumio sudo[27453]: pam_unix(sudo:session): session closed for user root Jun 18 19:25:17 volumio volumio[4392]: info: FusionDsp - Reporting Fusion DSP Disabled Jun 18 19:25:17 volumio volumio[4392]: info: Removing fusiondspeq DSP Signal Path Element Jun 18 19:25:17 volumio volumio[4392]: info: Check plugin dependencies Jun 18 19:25:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 19:25:18 volumio volumio[4392]: info: Rename folder Jun 18 19:25:18 volumio volumio[4392]: info: Move to category Jun 18 19:25:18 volumio volumio[4392]: info: Folder /tmp/downloaded_plugin.zip removed Jun 18 19:25:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 19:25:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 19:25:18 volumio volumio[4392]: info: Discovery: Getting this device information Jun 18 19:25:18 volumio volumio[4392]: info: CoreCommandRouter::volumioGetState Jun 18 19:25:18 volumio volumio[4392]: info: CorePlayQueue::getTrack 0 Jun 18 19:25:18 volumio volumio[4392]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 19:25:18 volumio volumio[4392]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 19:25:18 volumio volumio[4392]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 18 19:25:18 volumio volumio[4392]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jun 18 19:25:18 volumio volumio[4392]: at onErrorNT (internal/child_process.js:472:16) Jun 18 19:25:18 volumio volumio[4392]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Jun 18 19:25:18 volumio volumio[4392]: errno: -2, Jun 18 19:25:18 volumio volumio[4392]: code: 'ENOENT', Jun 18 19:25:18 volumio volumio[4392]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 18 19:25:18 volumio volumio[4392]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 18 19:25:18 volumio volumio[4392]: spawnargs: [ Jun 18 19:25:18 volumio volumio[4392]: '-p', Jun 18 19:25:18 volumio volumio[4392]: 9876, Jun 18 19:25:18 volumio volumio[4392]: '-o', Jun 18 19:25:18 volumio volumio[4392]: '/tmp/camilladsp.log', Jun 18 19:25:18 volumio volumio[4392]: '-l', Jun 18 19:25:18 volumio volumio[4392]: 'warn', Jun 18 19:25:18 volumio volumio[4392]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 18 19:25:18 volumio volumio[4392]: ] Jun 18 19:25:18 volumio volumio[4392]: } Jun 18 19:25:18 volumio volumio[4392]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 19:25:18 volumio sudo[27590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 19:24 Jun 18 19:25:18 volumio sudo[27590]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"