-- Logs begin at Thu 2025-09-18 00:29:32 CEST, end at Thu 2025-09-18 00:35:09 CEST. -- Sep 18 00:34:00 cigany volumio[855]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Sep 18 00:34:00 cigany volumio[855]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Sep 18 00:34:01 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Sep 18 00:34:01 cigany volumio[855]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Sep 18 00:34:02 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Sep 18 00:34:02 cigany volumio[855]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Sep 18 00:34:02 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Sep 18 00:34:02 cigany volumio[855]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Sep 18 00:34:02 cigany volumio[855]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Sep 18 00:34:03 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) Sep 18 00:34:03 cigany volumio[855]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Sep 18 00:34:03 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Sep 18 00:34:04 cigany volumio[855]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Sep 18 00:34:04 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Sep 18 00:34:04 cigany volumio[855]: info: Collecting zipp>=3.1.0; python_version < "3.10" (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) Sep 18 00:34:04 cigany volumio[855]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Sep 18 00:34:04 cigany volumio[855]: info: Building wheels for collected packages: camilladsp-plot Sep 18 00:34:04 cigany volumio[855]: info: Running setup.py bdist_wheel for camilladsp-plot: started Sep 18 00:34:06 cigany volumio[855]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Sep 18 00:34:06 cigany volumio[855]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-drjkxl1b/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Sep 18 00:34:06 cigany volumio[855]: info: Successfully built camilladsp-plot Sep 18 00:34:06 cigany volumio[855]: info: Installing collected packages: pkgutil-resolve-name, zipp, importlib-resources, pyrsistent, typing-extensions, importlib-metadata, jsonschema, camilladsp-plot Sep 18 00:34:07 cigany volumio[855]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Sep 18 00:34:07 cigany volumio[855]: info: copying hw detection script Sep 18 00:34:07 cigany volumio[855]: info: Detected cpu architecture as x86_64 Sep 18 00:34:09 cigany sudo[3300]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Sep 18 00:34:09 cigany sudo[3300]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:09 cigany volumio[855]: info: Reading package lists... Sep 18 00:34:10 cigany volumio[855]: info: Building dependency tree... Sep 18 00:34:10 cigany volumio[855]: info: Reading state information... Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=trace msg="emitting websocket event: not_playing" Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:m75gujipfrtyz8hoiblgfsgx4:collection","uri":"spotify:track:5Ht48BadOqg22f3lWQLLa8","play_origin":"playlist"}} Sep 18 00:34:10 cigany volumio[855]: error: Failed to decode event: not_playing Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=trace msg="emitting websocket event: will_play" Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=info msg="loaded track \"Pump Up\" (paused: false, position: 0ms, duration: 228387ms, prefetched: true)" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:m75gujipfrtyz8hoiblgfsgx4:collection","uri":"spotify:track:348qRYEPvNbPrSX5UsSqmn","play_origin":"playlist"}} Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=trace msg="scheduling prefetch in 199s" Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=trace msg="emitting websocket event: metadata" Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:348qRYEPvNbPrSX5UsSqmn","name":"Pump Up","artist_names":["Sx1nxwy"],"album_name":"Pump Up","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02dc9258b7bcbe8c7d94d36093","position":0,"duration":228387,"release_date":"year:2023 month:1 day:30","track_number":1,"disc_number":1}} Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 18 00:34:10 cigany go-librespot[1405]: time="2025-09-18T00:34:10+02:00" level=trace msg="emitting websocket event: playing" Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:m75gujipfrtyz8hoiblgfsgx4:collection","uri":"spotify:track:348qRYEPvNbPrSX5UsSqmn","resume":false,"play_origin":"playlist"}} Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: PUSH STATE SPOTIFY Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: {"status":"play","service":"spop","title":"Pump Up","artist":"Sx1nxwy","album":"Pump Up","albumart":"https://i.scdn.co/image/ab67616d00001e02dc9258b7bcbe8c7d94d36093","uri":"spotify:track:348qRYEPvNbPrSX5UsSqmn","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Sep 18 00:34:10 cigany volumio[855]: info: CoreCommandRouter::servicePushState Sep 18 00:34:10 cigany volumio[855]: info: CoreStateMachine::pushState Sep 18 00:34:10 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 00:34:10 cigany volumio[855]: info: CoreCommandRouter::volumioPushState Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: PUSH STATE SPOTIFY Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: {"status":"play","service":"spop","title":"Pump Up","artist":"Sx1nxwy","album":"Pump Up","albumart":"https://i.scdn.co/image/ab67616d00001e02dc9258b7bcbe8c7d94d36093","uri":"spotify:track:348qRYEPvNbPrSX5UsSqmn","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Sep 18 00:34:10 cigany volumio[855]: info: CoreCommandRouter::servicePushState Sep 18 00:34:10 cigany volumio[855]: info: CoreStateMachine::pushState Sep 18 00:34:10 cigany volumio[855]: info: CoreCommandRouter::volumioPushState Sep 18 00:34:10 cigany volumio[855]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Sep 18 00:34:11 cigany volumio[855]: info: The following NEW packages will be installed: Sep 18 00:34:11 cigany volumio[855]: info: drc Sep 18 00:34:13 cigany volumio[855]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Sep 18 00:34:13 cigany volumio[855]: info: Need to get 143 kB of archives. Sep 18 00:34:13 cigany volumio[855]: info: After this operation, 609 kB of additional disk space will be used. Sep 18 00:34:13 cigany volumio[855]: info: Get:1 http://archive.debian.org/debian buster/main amd64 drc amd64 3.2.2~dfsg0-2 [143 kB] Sep 18 00:34:13 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 00:34:13 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 00:34:13 cigany volumio[855]: info: Discovery: Getting this device information Sep 18 00:34:13 cigany volumio[855]: info: CoreCommandRouter::volumioGetState Sep 18 00:34:13 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 00:34:14 cigany volumio[855]: info: Fetched 143 kB in 1s (138 kB/s) Sep 18 00:34:14 cigany volumio[855]: info: Selecting previously unselected package drc. Sep 18 00:34:15 cigany volumio[855]: [620B blob data] Sep 18 00:34:15 cigany volumio[855]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_amd64.deb ... Sep 18 00:34:15 cigany volumio[855]: info: Unpacking drc (3.2.2~dfsg0-2) ... Sep 18 00:34:15 cigany volumio[855]: info: Setting up drc (3.2.2~dfsg0-2) ... Sep 18 00:34:15 cigany volumio[855]: info: Processing triggers for man-db (2.8.5-2) ... Sep 18 00:34:16 cigany sudo[3300]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:16 cigany sudo[2196]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:16 cigany volumio[855]: Plugin install end detected on script Sep 18 00:34:16 cigany volumio[855]: info: Install script completed Sep 18 00:34:16 cigany volumio[855]: info: Adding reference to registry Sep 18 00:34:16 cigany volumio[855]: info: Done installing plugin. Sep 18 00:34:16 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 18 00:34:16 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 18 00:34:16 cigany volumio[855]: info: Folder /tmp/plugins removed Sep 18 00:34:16 cigany volumio[855]: info: Folder /tmp/downloaded_plugin.zip removed Sep 18 00:34:16 cigany volumio[855]: info: Folder /data/temp removed Sep 18 00:34:23 cigany go-librespot[1405]: time="2025-09-18T00:34:23+02:00" level=debug msg="fetched chunk 4/19, size: 524288" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:34:28 cigany go-librespot[1405]: time="2025-09-18T00:34:28+02:00" level=trace msg="sent dealer ping" Sep 18 00:34:28 cigany go-librespot[1405]: time="2025-09-18T00:34:28+02:00" level=trace msg="received dealer pong" Sep 18 00:34:29 cigany volumio[855]: info: Enabling plugin fusiondsp Sep 18 00:34:29 cigany volumio[855]: info: Loading plugin "fusiondsp"... Sep 18 00:34:29 cigany volumio[855]: info: Preparing to generate the ALSA configuration file Sep 18 00:34:29 cigany volumio[855]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 18 00:34:29 cigany volumio[855]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 18 00:34:29 cigany volumio[855]: info: Reading ALSA contributions from plugins. Sep 18 00:34:29 cigany volumio[855]: info: Asound.conf file written Sep 18 00:34:29 cigany sudo[3380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 18 00:34:29 cigany sudo[3380]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:29 cigany sudo[3380]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:29 cigany volumio[855]: info: Output device has changed, restarting MPD Sep 18 00:34:30 cigany sudo[3386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:34:30 cigany sudo[3386]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany volumio[855]: info: Output device has changed, restarting Shairport Sync Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:30 cigany sudo[3388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:34:30 cigany sudo[3388]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany sudo[3386]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:30 cigany systemd[1]: Stopping Music Player Daemon... Sep 18 00:34:30 cigany volumio[855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:34:30 cigany volumio[855]: info: PLUGIN START: fusiondsp Sep 18 00:34:30 cigany volumio[855]: info: Loading i18n strings for locale en Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: FusionDsp - mixtype--------------------- Software Sep 18 00:34:30 cigany volumio[855]: info: Preparing to generate the ALSA configuration file Sep 18 00:34:30 cigany volumio[855]: info: Done. Sep 18 00:34:30 cigany volumio[855]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 18 00:34:30 cigany volumio[855]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 18 00:34:30 cigany volumio[855]: info: Reading ALSA contributions from plugins. Sep 18 00:34:30 cigany volumio[855]: info: MPD Permissions set Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: Starting Shairport Sync Sep 18 00:34:30 cigany systemd[1]: mpd.service: Succeeded. Sep 18 00:34:30 cigany systemd[1]: Stopped Music Player Daemon. Sep 18 00:34:30 cigany systemd[1]: Starting Music Player Daemon... Sep 18 00:34:30 cigany volumio[855]: info: Asound.conf file unchanged, so no further update is needed Sep 18 00:34:30 cigany volumio[855]: info: Output device has changed, restarting MPD Sep 18 00:34:30 cigany sudo[3402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:34:30 cigany sudo[3402]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:34:30 cigany systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:34:30 cigany systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:30 cigany sudo[3405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:34:30 cigany sudo[3405]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:30 cigany sudo[3402]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:30 cigany sudo[3405]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:30 cigany sudo[3401]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:34:30 cigany sudo[3401]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany sudo[3401]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:30 cigany volumio[855]: info: Output device has changed, restarting Shairport Sync Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:30 cigany sudo[3412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:34:30 cigany sudo[3412]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 18 00:34:30 cigany systemd[1]: mpd.service: Succeeded. Sep 18 00:34:30 cigany systemd[1]: Stopped Music Player Daemon. Sep 18 00:34:30 cigany systemd[1]: Starting Music Player Daemon... Sep 18 00:34:30 cigany volumio[855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:34:30 cigany volumio[855]: info: MPD Permissions set Sep 18 00:34:30 cigany volumio[855]: info: Shairport-Sync Started Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 18 00:34:30 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:30 cigany volumio[855]: info: Starting Shairport Sync Sep 18 00:34:30 cigany sudo[3418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:34:30 cigany sudo[3418]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany sudo[3418]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:30 cigany sudo[3426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:34:30 cigany sudo[3426]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:30 cigany systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:34:30 cigany systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:34:30 cigany systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:30 cigany systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:30 cigany sudo[3426]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:30 cigany volumio[855]: info: Shairport-Sync Started Sep 18 00:34:32 cigany mpd[3428]: Sep 18 00:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 18 00:34:32 cigany systemd[1]: Started Music Player Daemon. Sep 18 00:34:32 cigany sudo[3388]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:32 cigany sudo[3412]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:32 cigany volumio[855]: info: camilladsp service started and running in background, instance 1 Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: cannot open device 'volumioHw': Device or resource busy Sep 18 00:34:32 cigany volumio[855]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 18 00:34:32 cigany volumio[855]: cannot open device 'volumioHw': Device or resource busy Sep 18 00:34:32 cigany volumio[855]: info: FusionDsp loaded Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 00:34:32 cigany sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 18 00:34:32 cigany sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:32 cigany volumio[855]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 18 00:34:32 cigany volumio[855]: info: Adding Signal Path Element [object Object] Sep 18 00:34:32 cigany volumio[855]: info: Adding fusiondspeq DSP Signal Path Element Sep 18 00:34:32 cigany volumio[855]: info: FusionDsp - ---- installed callbackRead Sep 18 00:34:32 cigany volumio[855]: info: Enabling plugin fusiondsp Sep 18 00:34:32 cigany volumio[855]: info: Loading plugin "fusiondsp"... Sep 18 00:34:32 cigany volumio[855]: info: Applying required configuration parameters for plugin fusiondsp Sep 18 00:34:32 cigany volumio[855]: info: Preparing to generate the ALSA configuration file Sep 18 00:34:32 cigany volumio[855]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 18 00:34:32 cigany volumio[855]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 18 00:34:32 cigany volumio[855]: info: Reading ALSA contributions from plugins. Sep 18 00:34:32 cigany systemd[1]: Started FusionDsp Daemon. Sep 18 00:34:32 cigany volumio[855]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 18 00:34:32 cigany sudo[3461]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:32 cigany volumio[855]: error: MPD error: The expression evaluated to a falsy value: Sep 18 00:34:32 cigany volumio[855]: assert.ok(self.idling) Sep 18 00:34:32 cigany volumio[855]: error: The expression evaluated to a falsy value: Sep 18 00:34:32 cigany volumio[855]: assert.ok(self.idling) Sep 18 00:34:32 cigany volumio[855]: error: FusionDsp - WebSocket error: [object Object] Sep 18 00:34:32 cigany volumio[855]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 18 00:34:32 cigany volumio[855]: error: updateQueue error: null Sep 18 00:34:32 cigany volumio[855]: info: Asound.conf file unchanged, so no further update is needed Sep 18 00:34:32 cigany volumio[855]: info: Output device has changed, restarting MPD Sep 18 00:34:32 cigany volumio[855]: info: Output device has changed, restarting Shairport Sync Sep 18 00:34:32 cigany sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:32 cigany sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:32 cigany sudo[3465]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:32 cigany sudo[3467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:34:32 cigany sudo[3467]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:32 cigany systemd[1]: Stopping Music Player Daemon... Sep 18 00:34:32 cigany systemd[1]: mpd.service: Succeeded. Sep 18 00:34:32 cigany systemd[1]: Stopped Music Player Daemon. Sep 18 00:34:32 cigany systemd[1]: Starting Music Player Daemon... Sep 18 00:34:32 cigany volumio[855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:34:32 cigany volumio[855]: info: PLUGIN START: fusiondsp Sep 18 00:34:32 cigany volumio[855]: info: Loading i18n strings for locale en Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: FusionDsp - mixtype--------------------- Software Sep 18 00:34:32 cigany volumio[855]: info: Preparing to generate the ALSA configuration file Sep 18 00:34:32 cigany volumio[855]: info: Done. Sep 18 00:34:32 cigany volumio[855]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 18 00:34:32 cigany volumio[855]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 18 00:34:32 cigany volumio[855]: info: Reading ALSA contributions from plugins. Sep 18 00:34:32 cigany volumio[855]: info: MPD Permissions set Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 18 00:34:32 cigany sudo[3473]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:34:32 cigany sudo[3473]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:32 cigany sudo[3473]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:32 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:32 cigany volumio[855]: info: Starting Shairport Sync Sep 18 00:34:33 cigany volumio[855]: info: Asound.conf file unchanged, so no further update is needed Sep 18 00:34:33 cigany volumio[855]: info: Output device has changed, restarting MPD Sep 18 00:34:33 cigany sudo[3483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:34:33 cigany sudo[3483]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:33 cigany systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:34:33 cigany systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:34:33 cigany systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:33 cigany sudo[3486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:34:33 cigany sudo[3486]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:33 cigany systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:33 cigany sudo[3483]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:33 cigany volumio[855]: info: Output device has changed, restarting Shairport Sync Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:33 cigany sudo[3486]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:33 cigany sudo[3490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:34:33 cigany sudo[3490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:33 cigany systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 18 00:34:33 cigany systemd[1]: mpd.service: Succeeded. Sep 18 00:34:33 cigany systemd[1]: Stopped Music Player Daemon. Sep 18 00:34:33 cigany systemd[1]: Starting Music Player Daemon... Sep 18 00:34:33 cigany volumio[855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:34:33 cigany volumio[855]: 0+0 records in Sep 18 00:34:33 cigany volumio[855]: 0+0 records out Sep 18 00:34:33 cigany volumio[855]: 0 bytes copied, 0.000160706 s, 0.0 kB/s Sep 18 00:34:33 cigany sudo[3497]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:34:33 cigany sudo[3497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:33 cigany volumio[855]: info: MPD Permissions set Sep 18 00:34:33 cigany volumio[855]: info: Shairport-Sync Started Sep 18 00:34:33 cigany sudo[3497]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 18 00:34:33 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:33 cigany volumio[855]: info: Starting Shairport Sync Sep 18 00:34:33 cigany sudo[3523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:34:33 cigany sudo[3523]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:34 cigany systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:34:34 cigany systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:34:34 cigany systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:34 cigany systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:34 cigany sudo[3523]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:34 cigany volumio[855]: info: Shairport-Sync Started Sep 18 00:34:34 cigany volumio[855]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 18 00:34:34 cigany volumio[855]: 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 Sep 18 00:34:34 cigany volumio[855]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 18 00:34:34 cigany volumio[855]: info: camilladsp service started and running in background, instance 2 Sep 18 00:34:34 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:34 cigany go-librespot[1405]: time="2025-09-18T00:34:34+02:00" level=debug msg="fetched chunk 5/19, size: 524288" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:34:34 cigany volumio[855]: cannot open device 'volumioHw': Device or resource busy Sep 18 00:34:34 cigany volumio[855]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 18 00:34:34 cigany volumio[855]: cannot open device 'volumioHw': Device or resource busy Sep 18 00:34:34 cigany volumio[855]: info: FusionDsp loaded Sep 18 00:34:34 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 00:34:34 cigany volumio[855]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 18 00:34:34 cigany volumio[855]: info: Adding Signal Path Element [object Object] Sep 18 00:34:34 cigany volumio[855]: info: Updating fusiondspeq DSP Signal Path Element Sep 18 00:34:34 cigany volumio[855]: info: FusionDsp - ---- installed callbackRead Sep 18 00:34:34 cigany volumio[855]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 18 00:34:34 cigany volumio[855]: info: FusionDsp - ---- read samplerate, raw: Sep 18 00:34:34 cigany volumio[855]: error: FusionDsp - invalid sample rate Sep 18 00:34:34 cigany volumio[855]: info: FusionDsp - ---- read samplerate, raw: Sep 18 00:34:34 cigany volumio[855]: error: FusionDsp - invalid sample rate Sep 18 00:34:34 cigany volumio[855]: error: FusionDsp - WebSocket error: [object Object] Sep 18 00:34:35 cigany mpd[3504]: Sep 18 00:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 18 00:34:35 cigany systemd[1]: Started Music Player Daemon. Sep 18 00:34:35 cigany sudo[3629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 18 00:34:35 cigany sudo[3629]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:35 cigany sudo[3467]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:35 cigany sudo[3490]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:35 cigany sudo[3629]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:35 cigany volumio[855]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 18 00:34:35 cigany volumio[855]: error: MPD error: The expression evaluated to a falsy value: Sep 18 00:34:35 cigany volumio[855]: assert.ok(self.idling) Sep 18 00:34:35 cigany volumio[855]: error: The expression evaluated to a falsy value: Sep 18 00:34:35 cigany volumio[855]: assert.ok(self.idling) Sep 18 00:34:35 cigany volumio[855]: error: updateQueue error: null Sep 18 00:34:41 cigany volumio[855]: info: Enabling plugin fusiondsp Sep 18 00:34:41 cigany volumio[855]: info: Loading plugin "fusiondsp"... Sep 18 00:34:41 cigany volumio[855]: info: Applying required configuration parameters for plugin fusiondsp Sep 18 00:34:41 cigany volumio[855]: info: Preparing to generate the ALSA configuration file Sep 18 00:34:41 cigany volumio[855]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 18 00:34:41 cigany volumio[855]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 18 00:34:41 cigany volumio[855]: info: Reading ALSA contributions from plugins. Sep 18 00:34:42 cigany volumio[855]: info: Asound.conf file unchanged, so no further update is needed Sep 18 00:34:42 cigany volumio[855]: info: Output device has changed, restarting MPD Sep 18 00:34:42 cigany volumio[855]: info: Output device has changed, restarting Shairport Sync Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:42 cigany sudo[5174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:34:42 cigany sudo[5174]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany sudo[5174]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:42 cigany sudo[5176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:34:42 cigany sudo[5176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany systemd[1]: Stopping Music Player Daemon... Sep 18 00:34:42 cigany volumio[855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:34:42 cigany volumio[855]: info: PLUGIN START: fusiondsp Sep 18 00:34:42 cigany volumio[855]: info: Loading i18n strings for locale en Sep 18 00:34:42 cigany systemd[1]: mpd.service: Succeeded. Sep 18 00:34:42 cigany systemd[1]: Stopped Music Player Daemon. Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: FusionDsp - mixtype--------------------- Software Sep 18 00:34:42 cigany volumio[855]: info: Preparing to generate the ALSA configuration file Sep 18 00:34:42 cigany volumio[855]: info: Done. Sep 18 00:34:42 cigany volumio[855]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 18 00:34:42 cigany volumio[855]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 18 00:34:42 cigany volumio[855]: info: Reading ALSA contributions from plugins. Sep 18 00:34:42 cigany volumio[855]: info: MPD Permissions set Sep 18 00:34:42 cigany systemd[1]: Starting Music Player Daemon... Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany sudo[5182]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:34:42 cigany sudo[5182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany sudo[5182]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: Starting Shairport Sync Sep 18 00:34:42 cigany volumio[855]: info: Asound.conf file unchanged, so no further update is needed Sep 18 00:34:42 cigany volumio[855]: info: Output device has changed, restarting MPD Sep 18 00:34:42 cigany sudo[5222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:34:42 cigany sudo[5222]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:34:42 cigany sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:34:42 cigany volumio[855]: info: Output device has changed, restarting Shairport Sync Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:42 cigany systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:34:42 cigany systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:42 cigany sudo[5225]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:42 cigany systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:42 cigany sudo[5228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:34:42 cigany sudo[5228]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany sudo[5222]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:42 cigany systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 18 00:34:42 cigany systemd[1]: mpd.service: Succeeded. Sep 18 00:34:42 cigany systemd[1]: Stopped Music Player Daemon. Sep 18 00:34:42 cigany systemd[1]: Starting Music Player Daemon... Sep 18 00:34:42 cigany volumio[855]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:34:42 cigany volumio[855]: info: MPD Permissions set Sep 18 00:34:42 cigany volumio[855]: info: Shairport-Sync Started Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:42 cigany sudo[5236]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:34:42 cigany sudo[5236]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:42 cigany sudo[5236]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 18 00:34:42 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:43 cigany volumio[855]: info: Starting Shairport Sync Sep 18 00:34:43 cigany sudo[5274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:34:43 cigany sudo[5274]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:43 cigany systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:34:43 cigany systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:34:43 cigany systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:43 cigany systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:34:43 cigany sudo[5274]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:43 cigany volumio[855]: info: Shairport-Sync Started Sep 18 00:34:43 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 18 00:34:43 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 00:34:43 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 00:34:43 cigany volumio[855]: info: Discovery: Getting this device information Sep 18 00:34:43 cigany volumio[855]: info: CoreCommandRouter::volumioGetState Sep 18 00:34:43 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 00:34:44 cigany mpd[5252]: Sep 18 00:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 18 00:34:44 cigany systemd[1]: Started Music Player Daemon. Sep 18 00:34:44 cigany sudo[5176]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:44 cigany sudo[5228]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:44 cigany volumio[855]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 18 00:34:44 cigany volumio[855]: 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 Sep 18 00:34:44 cigany volumio[855]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 18 00:34:44 cigany volumio[855]: info: camilladsp service started and running in background, instance 3 Sep 18 00:34:44 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:34:44 cigany volumio[855]: cannot open device 'volumioHw': Device or resource busy Sep 18 00:34:44 cigany volumio[855]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 18 00:34:44 cigany volumio[855]: cannot open device 'volumioHw': Device or resource busy Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp loaded Sep 18 00:34:44 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 18 00:34:44 cigany volumio[855]: info: Adding Signal Path Element [object Object] Sep 18 00:34:44 cigany volumio[855]: info: Updating fusiondspeq DSP Signal Path Element Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp - ---- installed callbackRead Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp - ---- read samplerate, raw: Sep 18 00:34:44 cigany volumio[855]: error: FusionDsp - invalid sample rate Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp - ---- read samplerate, raw: Sep 18 00:34:44 cigany volumio[855]: error: FusionDsp - invalid sample rate Sep 18 00:34:44 cigany volumio[855]: info: FusionDsp - ---- read samplerate, raw: Sep 18 00:34:44 cigany volumio[855]: error: FusionDsp - invalid sample rate Sep 18 00:34:44 cigany sudo[5424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 18 00:34:44 cigany sudo[5424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:34:44 cigany sudo[5424]: pam_unix(sudo:session): session closed for user root Sep 18 00:34:44 cigany volumio[855]: error: FusionDsp - WebSocket error: [object Object] Sep 18 00:34:44 cigany volumio[855]: error: MPD error: The expression evaluated to a falsy value: Sep 18 00:34:44 cigany volumio[855]: assert.ok(self.idling) Sep 18 00:34:44 cigany volumio[855]: error: The expression evaluated to a falsy value: Sep 18 00:34:44 cigany volumio[855]: assert.ok(self.idling) Sep 18 00:34:44 cigany volumio[855]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Sep 18 00:34:44 cigany volumio[855]: error: updateQueue error: null Sep 18 00:34:46 cigany go-librespot[1405]: time="2025-09-18T00:34:46+02:00" level=debug msg="fetched chunk 6/19, size: 524288" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:34:56 cigany go-librespot[1405]: time="2025-09-18T00:34:56+02:00" level=debug msg="fetched chunk 7/19, size: 524288" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:34:58 cigany go-librespot[1405]: time="2025-09-18T00:34:58+02:00" level=trace msg="sent dealer ping" Sep 18 00:34:58 cigany go-librespot[1405]: time="2025-09-18T00:34:58+02:00" level=trace msg="received dealer pong" Sep 18 00:35:04 cigany volumio[855]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Sep 18 00:35:07 cigany volumio[855]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Sep 18 00:35:07 cigany volumio[855]: info: FusionDsp - Stopping FusionDsp service Sep 18 00:35:07 cigany volumio[855]: info: camilladsp stopping service pid 10577... Sep 18 00:35:07 cigany volumio[855]: info: camilladsp service terminated, instance 3 Sep 18 00:35:07 cigany go-librespot[1405]: time="2025-09-18T00:35:07+02:00" level=debug msg="fetched chunk 8/19, size: 524288" uri="spotify:track:348qRYEPvNbPrSX5UsSqmn" Sep 18 00:35:07 cigany sudo[10581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Sep 18 00:35:07 cigany sudo[10581]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:35:07 cigany volumio[3463]: Backend configuration: Sep 18 00:35:07 cigany volumio[3463]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 18 00:35:07 cigany volumio[3463]: active_config_txt: null Sep 18 00:35:07 cigany volumio[3463]: camilla_host: 127.0.0.1 Sep 18 00:35:07 cigany volumio[3463]: camilla_port: 9876 Sep 18 00:35:07 cigany volumio[3463]: coeff_dir: /data/INTERNAL/FusionDsp/filters Sep 18 00:35:07 cigany volumio[3463]: config_dir: /data/configuration/audio_interface/fusiondsp Sep 18 00:35:07 cigany volumio[3463]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 18 00:35:07 cigany volumio[3463]: log_file: /tmp/camilladsp.log Sep 18 00:35:07 cigany volumio[3463]: on_get_active_config: null Sep 18 00:35:07 cigany volumio[3463]: on_set_active_config: null Sep 18 00:35:07 cigany volumio[3463]: port: 5011 Sep 18 00:35:07 cigany volumio[3463]: supported_capture_types: null Sep 18 00:35:07 cigany volumio[3463]: supported_playback_types: null Sep 18 00:35:07 cigany volumio[3463]: update_config_symlink: false Sep 18 00:35:07 cigany volumio[3463]: update_config_txt: false Sep 18 00:35:07 cigany volumio[3463]: ======== Running on http://0.0.0.0:5011 ======== Sep 18 00:35:07 cigany volumio[3463]: (Press CTRL+C to quit) Sep 18 00:35:07 cigany systemd[1]: Stopping FusionDsp Daemon... Sep 18 00:35:07 cigany systemd[1]: fusiondsp.service: Succeeded. Sep 18 00:35:07 cigany systemd[1]: Stopped FusionDsp Daemon. Sep 18 00:35:07 cigany sudo[10581]: pam_unix(sudo:session): session closed for user root Sep 18 00:35:07 cigany volumio[855]: info: FusionDsp - Reporting Fusion DSP Disabled Sep 18 00:35:07 cigany volumio[855]: info: Removing fusiondspeq DSP Signal Path Element Sep 18 00:35:08 cigany volumio[855]: info: Check plugin dependencies Sep 18 00:35:08 cigany volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 00:35:08 cigany volumio[855]: info: Rename folder Sep 18 00:35:08 cigany volumio[855]: info: Folder /tmp/downloaded_plugin.zip removed Sep 18 00:35:08 cigany volumio[855]: info: Move to category Sep 18 00:35:08 cigany volumio[855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 00:35:08 cigany volumio[855]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 18 00:35:08 cigany volumio[855]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 18 00:35:08 cigany volumio[855]: at onErrorNT (internal/child_process.js:472:16) Sep 18 00:35:08 cigany volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Sep 18 00:35:08 cigany volumio[855]: errno: -2, Sep 18 00:35:08 cigany volumio[855]: code: 'ENOENT', Sep 18 00:35:08 cigany volumio[855]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 18 00:35:08 cigany volumio[855]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 18 00:35:08 cigany volumio[855]: spawnargs: [ Sep 18 00:35:08 cigany volumio[855]: '-p', Sep 18 00:35:08 cigany volumio[855]: 9876, Sep 18 00:35:08 cigany volumio[855]: '-o', Sep 18 00:35:08 cigany volumio[855]: '/tmp/camilladsp.log', Sep 18 00:35:08 cigany volumio[855]: '-l', Sep 18 00:35:08 cigany volumio[855]: 'warn', Sep 18 00:35:08 cigany volumio[855]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 18 00:35:08 cigany volumio[855]: ] Sep 18 00:35:08 cigany volumio[855]: } Sep 18 00:35:08 cigany volumio[855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 00:35:09 cigany sudo[10711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-18 00:34 Sep 18 00:35:09 cigany sudo[10711]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"