-- Logs begin at Mon 2024-10-07 14:36:37 JST, end at Mon 2024-10-07 15:46:16 JST. -- Oct 07 15:45:04 volumio volumio[805]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... Oct 07 15:45:04 volumio volumio[805]: info: Setting up cpp (4:8.3.0-1+rpi2) ... Oct 07 15:45:04 volumio volumio[805]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Oct 07 15:45:06 volumio volumio[805]: verbose: New Socket.io Connection to 192.168.100.30 from 192.168.100.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 07 15:45:07 volumio volumio[805]: info: CoreCommandRouter::volumioGetState Oct 07 15:45:07 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 15:45:07 volumio volumio[805]: info: Listing playlists Oct 07 15:45:07 volumio volumio[805]: info: CoreCommandRouter::volumioGetQueue Oct 07 15:45:07 volumio volumio[805]: info: CoreStateMachine::getQueue Oct 07 15:45:07 volumio volumio[805]: info: CorePlayQueue::getQueue Oct 07 15:45:07 volumio volumio[805]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 15:45:07 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 15:45:11 volumio volumio[805]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Oct 07 15:45:11 volumio volumio[805]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... Oct 07 15:45:11 volumio volumio[805]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Oct 07 15:45:11 volumio volumio[805]: info: Setting up gcc (4:8.3.0-1+rpi2) ... Oct 07 15:45:11 volumio volumio[805]: info: Setting up python3-secretstorage (2.3.1-2) ... Oct 07 15:45:15 volumio volumio[805]: info: Setting up python3-dev (3.7.3-1) ... Oct 07 15:45:16 volumio volumio[805]: info: Setting up g++-8 (8.3.0-6+rpi1) ... Oct 07 15:45:16 volumio volumio[805]: info: Setting up python3-keyring (17.1.1-1) ... Oct 07 15:45:20 volumio volumio[805]: info: Setting up g++ (4:8.3.0-1+rpi2) ... Oct 07 15:45:21 volumio volumio[805]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Oct 07 15:45:21 volumio volumio[805]: info: Setting up build-essential (12.6) ... Oct 07 15:45:21 volumio volumio[805]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Oct 07 15:45:33 volumio sudo[9989]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:33 volumio sudo[12154]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Oct 07 15:45:33 volumio sudo[12154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:33 volumio volumio[805]: info: lib/systemd/system/fusiondsp.service Oct 07 15:45:33 volumio sudo[12154]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:35 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 07 15:45:35 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 07 15:45:41 volumio volumio[805]: info: Enabling plugin fusiondsp Oct 07 15:45:41 volumio volumio[805]: info: Loading plugin "fusiondsp"... Oct 07 15:45:44 volumio volumio[805]: info: Preparing to generate the ALSA configuration file Oct 07 15:45:44 volumio volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 15:45:44 volumio volumio[805]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 07 15:45:44 volumio volumio[805]: info: Reading ALSA contributions from plugins. Oct 07 15:45:44 volumio volumio[805]: Upnp client error: Error: This socket has been ended by the other party Oct 07 15:45:46 volumio volumio[805]: info: Asound.conf file unchanged, so no further update is needed Oct 07 15:45:46 volumio volumio[805]: info: Output device has changed, restarting MPD Oct 07 15:45:47 volumio volumio[805]: info: Output device has changed, restarting Shairport Sync Oct 07 15:45:47 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:47 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 15:45:47 volumio sudo[12223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 15:45:47 volumio sudo[12223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:47 volumio sudo[12223]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:47 volumio sudo[12225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 15:45:47 volumio sudo[12225]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:47 volumio volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 15:45:47 volumio volumio[805]: info: PLUGIN START: fusiondsp Oct 07 15:45:47 volumio systemd[1]: Stopping Music Player Daemon... Oct 07 15:45:47 volumio volumio[805]: info: Loading i18n strings for locale ja Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: FusionDsp - mixtype--------------------- Software Oct 07 15:45:48 volumio volumio[805]: info: Preparing to generate the ALSA configuration file Oct 07 15:45:48 volumio volumio[805]: info: Done. Oct 07 15:45:48 volumio volumio[805]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 07 15:45:48 volumio volumio[805]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 15:45:48 volumio volumio[805]: info: Reading ALSA contributions from plugins. Oct 07 15:45:48 volumio volumio[805]: info: MPD Permissions set Oct 07 15:45:48 volumio volumio[805]: error: Upnp client error: Error: read ECONNRESET Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:48 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 15:45:49 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:49 volumio volumio[805]: info: Starting Shairport Sync Oct 07 15:45:49 volumio volumio[805]: info: Asound.conf file written Oct 07 15:45:49 volumio sudo[12237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 15:45:49 volumio sudo[12237]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:50 volumio sudo[12239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 07 15:45:50 volumio sudo[12239]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 15:45:50 volumio sudo[12239]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:50 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 07 15:45:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 15:45:50 volumio systemd[1]: mpd.service: Succeeded. Oct 07 15:45:50 volumio systemd[1]: Stopped Music Player Daemon. Oct 07 15:45:50 volumio systemd[1]: Starting Music Player Daemon... Oct 07 15:45:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 15:45:50 volumio sudo[12237]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:51 volumio volumio[805]: info: Output device has changed, restarting MPD Oct 07 15:45:51 volumio sudo[12243]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 15:45:51 volumio sudo[12243]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:51 volumio sudo[12243]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:52 volumio volumio[805]: info: Output device has changed, restarting Shairport Sync Oct 07 15:45:52 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:52 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 15:45:52 volumio sudo[12250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 15:45:52 volumio sudo[12250]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:52 volumio sudo[12250]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:52 volumio sudo[12253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 15:45:52 volumio sudo[12253]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:52 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 15:45:52 volumio systemd[1]: mpd.service: Succeeded. Oct 07 15:45:52 volumio systemd[1]: Stopped Music Player Daemon. Oct 07 15:45:52 volumio systemd[1]: Starting Music Player Daemon... Oct 07 15:45:53 volumio volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 15:45:53 volumio sudo[12259]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 15:45:53 volumio sudo[12259]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:53 volumio sudo[12259]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:53 volumio volumio[805]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 15:45:53 volumio volumio[805]: info: camilladsp service started and running in background, instance 1 Oct 07 15:45:53 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 15:45:53 volumio volumio[805]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 15:45:54 volumio volumio[805]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 07 15:45:54 volumio volumio[805]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 15:45:54 volumio volumio[805]: info: FusionDsp loaded Oct 07 15:45:54 volumio volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 15:45:54 volumio sudo[12272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 15:45:54 volumio sudo[12272]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:54 volumio volumio[805]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 15:45:54 volumio volumio[805]: info: Adding Signal Path Element [object Object] Oct 07 15:45:54 volumio volumio[805]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 15:45:54 volumio volumio[805]: info: FusionDsp - ---- installed callbackRead Oct 07 15:45:55 volumio systemd[1]: Started FusionDsp Daemon. Oct 07 15:45:55 volumio sudo[12272]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:55 volumio volumio[805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 15:45:55 volumio volumio-remote-updater[374]: [2024-10-07 15:45:55] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 07 15:45:55 volumio volumio[805]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 15:45:55 volumio volumio[805]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 15:45:55 volumio volumio[805]: at onErrorNT (internal/child_process.js:465:16) Oct 07 15:45:55 volumio volumio[805]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 07 15:45:55 volumio volumio[805]: at runNextTicks (internal/process/task_queues.js:62:3) Oct 07 15:45:55 volumio volumio[805]: at listOnTimeout (internal/timers.js:523:9) Oct 07 15:45:55 volumio volumio[805]: at processTimers (internal/timers.js:497:7) { Oct 07 15:45:55 volumio volumio[805]: errno: -2, Oct 07 15:45:55 volumio volumio[805]: code: 'ENOENT', Oct 07 15:45:55 volumio volumio[805]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 15:45:55 volumio volumio[805]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 15:45:55 volumio volumio[805]: spawnargs: [ Oct 07 15:45:55 volumio volumio[805]: '-p', Oct 07 15:45:55 volumio volumio[805]: 9876, Oct 07 15:45:55 volumio volumio[805]: '-o', Oct 07 15:45:55 volumio volumio[805]: '/tmp/camilladsp.log', Oct 07 15:45:55 volumio volumio[805]: '-l', Oct 07 15:45:55 volumio volumio[805]: 'warn', Oct 07 15:45:55 volumio volumio[805]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 15:45:55 volumio volumio[805]: ] Oct 07 15:45:55 volumio volumio[805]: } Oct 07 15:45:56 volumio volumio[805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 15:45:56 volumio volumio[12274]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 07 15:45:56 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 07 15:45:56 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 07 15:45:57 volumio sudo[12278]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Oct 07 15:45:57 volumio sudo[12278]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:57 volumio sudo[12278]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:57 volumio sudo[12280]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Oct 07 15:45:57 volumio sudo[12280]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:57 volumio sudo[12280]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:58 volumio sudo[12282]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Oct 07 15:45:58 volumio sudo[12282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 15:45:58 volumio sudo[12282]: pam_unix(sudo:session): session closed for user root Oct 07 15:45:58 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 07 15:45:58 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Oct 07 15:45:58 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 07 15:45:58 volumio systemd[1]: Started FusionDsp Daemon. Oct 07 15:46:00 volumio volumio-remote-updater[374]: [2024-10-07 15:46:00] [connect] Successful connection Oct 07 15:46:13 volumio volumio-remote-updater[374]: [2024-10-07 15:46:13] [connect] Successful connection Oct 07 15:46:15 volumio mpd[12267]: Oct 07 15:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 15:46:15 volumio systemd[1]: Started Music Player Daemon. Oct 07 15:46:15 volumio sudo[12225]: pam_unix(sudo:session): session closed for user root Oct 07 15:46:15 volumio sudo[12253]: pam_unix(sudo:session): session closed for user root Oct 07 15:46:15 volumio volumio[12285]: Traceback (most recent call last): Oct 07 15:46:15 volumio volumio[12285]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Oct 07 15:46:15 volumio volumio[12285]: from camilladsp import CamillaConnection Oct 07 15:46:15 volumio volumio[12285]: ModuleNotFoundError: No module named 'camilladsp' Oct 07 15:46:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 07 15:46:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 07 15:46:16 volumio sudo[12297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 15:45 Oct 07 15:46:16 volumio sudo[12297]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"