-- Logs begin at Thu 2025-09-04 09:38:38 MSK, end at Thu 2025-09-04 11:49:39 MSK. -- Sep 04 11:48:01 volumio go-librespot[1369]: time="2025-09-04T11:48:01+03:00" level=debug msg="fetched chunk 7/12, size: 524288" uri="spotify:track:0wtSOxnaPIv9i4aC7Vt6eO" Sep 04 11:48:03 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:03 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:03 Sep 04 11:48:03 volumio sudo[2054]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:03 Sep 04 11:48:03 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:03 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:03 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:03 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:03 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:05 volumio volumio[1106]: info: Enabling plugin fusiondsp Sep 04 11:48:05 volumio volumio[1106]: info: Loading plugin "fusiondsp"... Sep 04 11:48:05 volumio volumio[1106]: info: Applying required configuration parameters for plugin fusiondsp Sep 04 11:48:05 volumio volumio[1106]: info: Preparing to generate the ALSA configuration file Sep 04 11:48:05 volumio volumio[1106]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 04 11:48:05 volumio volumio[1106]: info: Reading ALSA contributions from plugins. Sep 04 11:48:05 volumio volumio[1106]: info: Asound.conf file unchanged, so no further update is needed Sep 04 11:48:05 volumio volumio[1106]: info: Output device has changed, restarting MPD Sep 04 11:48:05 volumio sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 11:48:05 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:05 volumio volumio[1106]: info: Output device has changed, restarting Shairport Sync Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 11:48:05 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 11:48:05 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio systemd[1]: Stopping Music Player Daemon... Sep 04 11:48:05 volumio systemd[1]: mpd.service: Succeeded. Sep 04 11:48:05 volumio systemd[1]: Stopped Music Player Daemon. Sep 04 11:48:05 volumio volumio[1106]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 11:48:05 volumio volumio[1106]: info: PLUGIN START: fusiondsp Sep 04 11:48:05 volumio volumio[1106]: info: Loading i18n strings for locale ru Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: FusionDsp - mixtype--------------------- None Sep 04 11:48:05 volumio volumio[1106]: info: Preparing to generate the ALSA configuration file Sep 04 11:48:05 volumio systemd[1]: Starting Music Player Daemon... Sep 04 11:48:05 volumio volumio[1106]: info: Done. Sep 04 11:48:05 volumio volumio[1106]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 04 11:48:05 volumio volumio[1106]: info: Reading ALSA contributions from plugins. Sep 04 11:48:05 volumio volumio[1106]: info: MPD Permissions set Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio sudo[2462]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 04 11:48:05 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:05 volumio volumio[1106]: info: Starting Shairport Sync Sep 04 11:48:05 volumio volumio[1106]: info: Asound.conf file unchanged, so no further update is needed Sep 04 11:48:05 volumio volumio[1106]: info: Output device has changed, restarting MPD Sep 04 11:48:05 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 11:48:05 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 11:48:05 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 04 11:48:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 04 11:48:05 volumio sudo[2490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 11:48:05 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio volumio[1106]: info: Output device has changed, restarting Shairport Sync Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 11:48:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 11:48:05 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:05 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:05 volumio sudo[2492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 11:48:05 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio volumio[1106]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 11:48:05 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 04 11:48:05 volumio systemd[1]: mpd.service: Succeeded. Sep 04 11:48:05 volumio volumio[1106]: info: MPD Permissions set Sep 04 11:48:05 volumio volumio[1106]: info: Shairport-Sync Started Sep 04 11:48:05 volumio systemd[1]: Stopped Music Player Daemon. Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio systemd[1]: Starting Music Player Daemon... Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 04 11:48:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:05 volumio volumio[1106]: info: Starting Shairport Sync Sep 04 11:48:05 volumio sudo[2500]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 04 11:48:05 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio sudo[2515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 11:48:05 volumio sudo[2515]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:05 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 11:48:05 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 04 11:48:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 04 11:48:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 11:48:05 volumio sudo[2515]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:05 volumio volumio[1106]: info: Shairport-Sync Started Sep 04 11:48:06 volumio mpd[2519]: Sep 04 11:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 11:48:06 volumio systemd[1]: Started Music Player Daemon. Sep 04 11:48:06 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:06 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:06 volumio volumio[1106]: error: MPD error: The expression evaluated to a falsy value: Sep 04 11:48:06 volumio volumio[1106]: assert.ok(self.idling) Sep 04 11:48:06 volumio volumio[1106]: error: The expression evaluated to a falsy value: Sep 04 11:48:06 volumio volumio[1106]: assert.ok(self.idling) Sep 04 11:48:06 volumio volumio[1106]: error: updateQueue error: null Sep 04 11:48:07 volumio volumio[1106]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 04 11:48:07 volumio volumio[1106]: 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 04 11:48:07 volumio volumio[1106]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 04 11:48:07 volumio volumio[1106]: info: camilladsp service started and running in background, instance 2 Sep 04 11:48:07 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:48:07 volumio volumio[1106]: cannot open device 'volumioHw': Device or resource busy Sep 04 11:48:07 volumio volumio[1106]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 04 11:48:07 volumio volumio[1106]: cannot open device 'volumioHw': Device or resource busy Sep 04 11:48:07 volumio volumio[1106]: info: FusionDsp loaded Sep 04 11:48:07 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 11:48:07 volumio volumio[1106]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 04 11:48:07 volumio volumio[1106]: info: Adding Signal Path Element [object Object] Sep 04 11:48:07 volumio volumio[1106]: info: Updating fusiondspeq DSP Signal Path Element Sep 04 11:48:07 volumio volumio[1106]: info: FusionDsp - ---- installed callbackRead Sep 04 11:48:07 volumio volumio[1106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 11:48:07 volumio sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 04 11:48:07 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:07 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:07 volumio volumio[1106]: info: FusionDsp - ---- read samplerate, raw: Sep 04 11:48:07 volumio volumio[1106]: error: FusionDsp - invalid sample rate Sep 04 11:48:07 volumio volumio[1106]: info: FusionDsp - ---- read samplerate, raw: Sep 04 11:48:07 volumio volumio[1106]: error: FusionDsp - invalid sample rate Sep 04 11:48:07 volumio volumio[1106]: error: FusionDsp - WebSocket error: [object Object] Sep 04 11:48:07 volumio volumio[1106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 04 11:48:08 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:09 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:09 Sep 04 11:48:09 volumio sudo[3870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:09 Sep 04 11:48:09 volumio sudo[3870]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:09 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:09 volumio sudo[3870]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:09 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:09 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:13 volumio go-librespot[1369]: time="2025-09-04T11:48:13+03:00" level=debug msg="fetched chunk 8/12, size: 524288" uri="spotify:track:0wtSOxnaPIv9i4aC7Vt6eO" Sep 04 11:48:14 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:14 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:14 Sep 04 11:48:14 volumio sudo[6259]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:14 Sep 04 11:48:14 volumio sudo[6259]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:14 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:14 volumio sudo[6259]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:14 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:14 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:16 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 11:48:19 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:19 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:19 Sep 04 11:48:19 volumio sudo[8360]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:19 Sep 04 11:48:19 volumio sudo[8360]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:19 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:19 volumio sudo[8360]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:19 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:19 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:21 volumio go-librespot[1369]: time="2025-09-04T11:48:21+03:00" level=trace msg="sent dealer ping" Sep 04 11:48:21 volumio go-librespot[1369]: time="2025-09-04T11:48:21+03:00" level=trace msg="received dealer pong" Sep 04 11:48:24 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:24 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:24 Sep 04 11:48:24 volumio sudo[10431]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:24 Sep 04 11:48:24 volumio sudo[10431]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:24 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:24 volumio sudo[10431]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:24 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:24 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:27 volumio go-librespot[1369]: time="2025-09-04T11:48:27+03:00" level=debug msg="fetched chunk 9/12, size: 524288" uri="spotify:track:0wtSOxnaPIv9i4aC7Vt6eO" Sep 04 11:48:29 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:30 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:30 Sep 04 11:48:30 volumio sudo[12518]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:30 Sep 04 11:48:30 volumio sudo[12518]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:30 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:30 volumio sudo[12518]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:30 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:30 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:30 volumio volumio[1106]: info: CoreCommandRouter::volumioGetState Sep 04 11:48:32 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 11:48:32 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 11:48:35 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:35 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:35 Sep 04 11:48:35 volumio sudo[14591]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:35 Sep 04 11:48:35 volumio sudo[14591]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:35 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:35 volumio sudo[14591]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:35 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:35 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:38 volumio volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 11:48:38 volumio volumio[1106]: info: FusionDsp - Sep 04 11:48:38 volumio volumio[1106]: info: FusionDsp - undefined Sep 04 11:48:40 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:40 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:40 Sep 04 11:48:40 volumio sudo[16628]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:40 Sep 04 11:48:40 volumio sudo[16628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:40 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:40 volumio sudo[16628]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:40 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:40 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:41 volumio go-librespot[1369]: time="2025-09-04T11:48:41+03:00" level=debug msg="fetched chunk 10/12, size: 524288" uri="spotify:track:0wtSOxnaPIv9i4aC7Vt6eO" Sep 04 11:48:45 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:46 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:45 Sep 04 11:48:46 volumio sudo[18698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:45 Sep 04 11:48:46 volumio sudo[18698]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:46 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:46 volumio sudo[18698]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:46 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:46 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:51 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:51 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:51 Sep 04 11:48:51 volumio sudo[20754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:51 Sep 04 11:48:51 volumio sudo[20754]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:51 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:51 volumio sudo[20754]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:51 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:51 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:48:51 volumio volumio[1106]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Sep 04 11:48:51 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Sep 04 11:48:51 volumio go-librespot[1369]: time="2025-09-04T11:48:51+03:00" level=trace msg="sent dealer ping" Sep 04 11:48:51 volumio go-librespot[1369]: time="2025-09-04T11:48:51+03:00" level=trace msg="received dealer pong" Sep 04 11:48:52 volumio volumio[1106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 11:48:52 volumio volumio[1106]: error: FusionDsp - WebSocket error: [object Object] Sep 04 11:48:52 volumio volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 11:48:52 volumio volumio[1106]: info: CoreCommandRouter::Close All Modals sent Sep 04 11:48:52 volumio volumio[1106]: info: FusionDsp - no preset used Sep 04 11:48:52 volumio volumio[1106]: info: FusionDsp - undefined Sep 04 11:48:54 volumio go-librespot[1369]: time="2025-09-04T11:48:54+03:00" level=debug msg="fetched chunk 11/12, size: 524288" uri="spotify:track:0wtSOxnaPIv9i4aC7Vt6eO" Sep 04 11:48:56 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:48:56 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:48:56 Sep 04 11:48:56 volumio sudo[22697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:48:56 Sep 04 11:48:56 volumio sudo[22697]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:48:56 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:48:56 volumio sudo[22697]: pam_unix(sudo:session): session closed for user root Sep 04 11:48:56 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:48:56 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:01 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:01 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:01 Sep 04 11:49:01 volumio sudo[24798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:01 Sep 04 11:49:01 volumio sudo[24798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:01 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:01 volumio sudo[24798]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:01 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:01 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:06 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:07 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:06 Sep 04 11:49:07 volumio sudo[26897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:06 Sep 04 11:49:07 volumio sudo[26897]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:07 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:07 volumio sudo[26897]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:07 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:07 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:07 volumio go-librespot[1369]: time="2025-09-04T11:49:07+03:00" level=debug msg="fetched chunk 12/12, size: 300837" uri="spotify:track:0wtSOxnaPIv9i4aC7Vt6eO" Sep 04 11:49:08 volumio volumio[1106]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Sep 04 11:49:08 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Sep 04 11:49:08 volumio volumio[1106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 11:49:08 volumio volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 11:49:08 volumio volumio[1106]: info: CoreCommandRouter::Close All Modals sent Sep 04 11:49:08 volumio volumio[1106]: error: FusionDsp - WebSocket error: [object Object] Sep 04 11:49:08 volumio volumio[1106]: info: FusionDsp - no preset used Sep 04 11:49:08 volumio volumio[1106]: info: FusionDsp - undefined Sep 04 11:49:12 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:12 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:12 Sep 04 11:49:12 volumio sudo[28866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:12 Sep 04 11:49:12 volumio sudo[28866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:12 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:12 volumio sudo[28866]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:12 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:12 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:13 volumio volumio[1106]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Sep 04 11:49:13 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Sep 04 11:49:13 volumio volumio[1106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 11:49:13 volumio volumio[1106]: info: FusionDsp - Effects disabled Sep 04 11:49:13 volumio volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 11:49:13 volumio volumio[1106]: info: CoreCommandRouter::Close All Modals sent Sep 04 11:49:13 volumio volumio[1106]: error: FusionDsp - WebSocket error: [object Object] Sep 04 11:49:13 volumio volumio[1106]: info: FusionDsp - no preset used Sep 04 11:49:13 volumio volumio[1106]: info: FusionDsp - undefined Sep 04 11:49:17 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:17 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:17 Sep 04 11:49:17 volumio sudo[30796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:17 Sep 04 11:49:17 volumio sudo[30796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:17 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:17 volumio sudo[30796]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:17 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:17 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:21 volumio go-librespot[1369]: time="2025-09-04T11:49:21+03:00" level=trace msg="received accesspoint ping" Sep 04 11:49:21 volumio go-librespot[1369]: time="2025-09-04T11:49:21+03:00" level=trace msg="received accesspoint pong ack" Sep 04 11:49:21 volumio go-librespot[1369]: time="2025-09-04T11:49:21+03:00" level=trace msg="sent dealer ping" Sep 04 11:49:21 volumio go-librespot[1369]: time="2025-09-04T11:49:21+03:00" level=trace msg="received dealer pong" Sep 04 11:49:22 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:22 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:22 Sep 04 11:49:22 volumio sudo[436]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:22 Sep 04 11:49:22 volumio sudo[436]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:22 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:22 volumio sudo[436]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:22 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:22 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:24 volumio go-librespot[1369]: time="2025-09-04T11:49:24+03:00" level=debug msg="prefetching next track" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:24 volumio go-librespot[1369]: time="2025-09-04T11:49:24+03:00" level=debug msg="selected format OGG_VORBIS_320 (988902f4119d4f5cdc038701d9fde1ceb4e64224)" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:24 volumio go-librespot[1369]: time="2025-09-04T11:49:24+03:00" level=debug msg="requested aes key for file 988902f4119d4f5cdc038701d9fde1ceb4e64224, gid: 6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:24 volumio go-librespot[1369]: time="2025-09-04T11:49:24+03:00" level=trace msg="found 2 cdn urls" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:24 volumio go-librespot[1369]: time="2025-09-04T11:49:24+03:00" level=debug msg="fetched first chunk of 18, total size is 9020356 bytes" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:24 volumio go-librespot[1369]: time="2025-09-04T11:49:24+03:00" level=info msg="prefetched track \"All Night Long (all Night)\" (duration: 229046ms)" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:25 volumio go-librespot[1369]: time="2025-09-04T11:49:25+03:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:25 volumio go-librespot[1369]: time="2025-09-04T11:49:25+03:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:25 volumio go-librespot[1369]: time="2025-09-04T11:49:25+03:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:6uteqvtnxNOzwVDCrP3wmL" Sep 04 11:49:27 volumio volumio[1106]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Sep 04 11:49:27 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Sep 04 11:49:27 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:28 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:28 Sep 04 11:49:28 volumio sudo[2608]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:28 Sep 04 11:49:28 volumio sudo[2608]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:28 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:28 volumio sudo[2608]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:28 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:28 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:28 volumio volumio[1106]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 11:49:28 volumio volumio[1106]: error: FusionDsp - WebSocket error: [object Object] Sep 04 11:49:28 volumio volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 11:49:28 volumio volumio[1106]: info: CoreCommandRouter::Close All Modals sent Sep 04 11:49:28 volumio volumio[1106]: info: FusionDsp - no preset used Sep 04 11:49:28 volumio volumio[1106]: info: FusionDsp - undefined Sep 04 11:49:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 11:49:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 11:49:33 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:33 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:33 Sep 04 11:49:33 volumio sudo[4549]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:33 Sep 04 11:49:33 volumio sudo[4549]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:33 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:33 volumio sudo[4549]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:33 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:33 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:37 volumio volumio[1106]: info: Starting Uninstall of plugin audio_interface - fusiondsp Sep 04 11:49:37 volumio volumio[1106]: info: Uninstalling plugin fusiondsp Sep 04 11:49:37 volumio volumio[1106]: info: FusionDsp - Stopping FusionDsp service Sep 04 11:49:37 volumio volumio[1106]: info: camilladsp stopping service pid 6302... Sep 04 11:49:37 volumio volumio[1106]: info: camilladsp service terminated, instance 2 Sep 04 11:49:37 volumio volumio[1106]: info: Disabling plugin fusiondsp Sep 04 11:49:37 volumio volumio[1106]: info: Preparing to generate the ALSA configuration file Sep 04 11:49:37 volumio sudo[6319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Sep 04 11:49:37 volumio sudo[6319]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:37 volumio volumio[499]: Backend configuration: Sep 04 11:49:37 volumio volumio[499]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 04 11:49:37 volumio volumio[499]: active_config_txt: null Sep 04 11:49:37 volumio volumio[499]: camilla_host: 127.0.0.1 Sep 04 11:49:37 volumio volumio[499]: camilla_port: 9876 Sep 04 11:49:37 volumio volumio[499]: coeff_dir: /data/INTERNAL/FusionDsp/filters Sep 04 11:49:37 volumio volumio[499]: config_dir: /data/configuration/audio_interface/fusiondsp Sep 04 11:49:37 volumio volumio[499]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 04 11:49:37 volumio volumio[499]: log_file: /tmp/camilladsp.log Sep 04 11:49:37 volumio volumio[499]: on_get_active_config: null Sep 04 11:49:37 volumio volumio[499]: on_set_active_config: null Sep 04 11:49:37 volumio volumio[499]: port: 5011 Sep 04 11:49:37 volumio volumio[499]: supported_capture_types: null Sep 04 11:49:37 volumio volumio[499]: supported_playback_types: null Sep 04 11:49:37 volumio volumio[499]: update_config_symlink: false Sep 04 11:49:37 volumio volumio[499]: update_config_txt: false Sep 04 11:49:37 volumio volumio[499]: ======== Running on http://0.0.0.0:5011 ======== Sep 04 11:49:37 volumio volumio[499]: (Press CTRL+C to quit) Sep 04 11:49:37 volumio systemd[1]: Stopping FusionDsp Daemon... Sep 04 11:49:37 volumio volumio[1106]: info: Asound.conf file written Sep 04 11:49:37 volumio sudo[6329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 04 11:49:37 volumio sudo[6329]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:37 volumio systemd[1]: fusiondsp.service: Succeeded. Sep 04 11:49:37 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 04 11:49:37 volumio sudo[6319]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:37 volumio sudo[6329]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:37 volumio volumio[1106]: info: Output device has changed, restarting MPD Sep 04 11:49:38 volumio sudo[6334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 11:49:38 volumio sudo[6334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio sudo[6334]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio volumio[1106]: info: Output device has changed, restarting Shairport Sync Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 11:49:38 volumio sudo[6337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 11:49:38 volumio sudo[6337]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio systemd[1]: Stopping Music Player Daemon... Sep 04 11:49:38 volumio volumio[1106]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 11:49:38 volumio systemd[1]: mpd.service: Succeeded. Sep 04 11:49:38 volumio systemd[1]: Stopped Music Player Daemon. Sep 04 11:49:38 volumio volumio[1106]: info: Checking if uninstall.sh is present Sep 04 11:49:38 volumio volumio[1106]: info: MPD Permissions set Sep 04 11:49:38 volumio volumio[1106]: info: FusionDsp - Reporting Fusion DSP Disabled Sep 04 11:49:38 volumio volumio[1106]: info: Removing fusiondspeq DSP Signal Path Element Sep 04 11:49:38 volumio volumio[1106]: info: Executing uninstall.sh Sep 04 11:49:38 volumio systemd[1]: Starting Music Player Daemon... Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 04 11:49:38 volumio sudo[6345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Sep 04 11:49:38 volumio sudo[6342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 04 11:49:38 volumio sudo[6342]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio sudo[6345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio sudo[6342]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 11:49:38 volumio volumio[1106]: info: Starting Shairport Sync Sep 04 11:49:38 volumio sudo[6365]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /lib/systemd/system/fusiondsp.service Sep 04 11:49:38 volumio sudo[6365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio sudo[6365]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio sudo[6373]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 04 11:49:38 volumio sudo[6373]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio systemd[1]: Reloading. Sep 04 11:49:38 volumio sudo[6375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 11:49:38 volumio sudo[6375]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio volumio-time-update[707]: volumio-time-update-util: Fetching time from Volumio... Sep 04 11:49:38 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 04 11:49:38 volumio volumio-time-update[707]: volumio-time-update-util: Setting system time to: 2025-09-04 11:49:38 Sep 04 11:49:38 volumio sudo[6433]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-04 11:49:38 Sep 04 11:49:38 volumio sudo[6433]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 11:49:38 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 04 11:49:38 volumio sudo[6373]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio volumio-time-update[707]: Failed to set time: Automatic time synchronization is enabled Sep 04 11:49:38 volumio sudo[6433]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio volumio-time-update[707]: volumio-time-update-util: Error setting time. Sep 04 11:49:38 volumio volumio-time-update[707]: volumio-time-update-util: Retrying in 5 seconds... Sep 04 11:49:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 11:49:38 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 04 11:49:38 volumio sudo[6345]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 04 11:49:38 volumio volumio[1106]: info: Uninstall script completed Sep 04 11:49:38 volumio volumio[1106]: info: Removing plugin fusiondsp from configuration Sep 04 11:49:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 11:49:38 volumio sudo[6375]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:38 volumio mpd[6360]: Sep 04 11:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 11:49:38 volumio systemd[1]: Started Music Player Daemon. Sep 04 11:49:38 volumio sudo[6337]: pam_unix(sudo:session): session closed for user root Sep 04 11:49:39 volumio volumio[1106]: info: Successfully removed fusiondsp configuration files Sep 04 11:49:39 volumio volumio[1106]: info: Plugin folders cleanup Sep 04 11:49:39 volumio volumio[1106]: info: Scanning into folder /volumio/app/plugins/ Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category audio_interface Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category miscellanea Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category music_service Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category plugins.json Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category system_controller Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category user_interface Sep 04 11:49:39 volumio volumio[1106]: info: Scanning into folder /data/plugins/ Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category audio_interface Sep 04 11:49:39 volumio volumio[1106]: info: Cleaning folder for fusiondsp Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category music_service Sep 04 11:49:39 volumio volumio[1106]: info: Scanning category user_interface Sep 04 11:49:39 volumio volumio[1106]: info: Plugin folders cleanup completed Sep 04 11:49:39 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 11:49:39 volumio volumio[1106]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":false}] Sep 04 11:49:39 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 11:49:39 volumio volumio[1106]: info: Shairport-Sync Started Sep 04 11:49:39 volumio volumio[1106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 11:49:39 volumio volumio[1106]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 04 11:49:39 volumio volumio[1106]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 04 11:49:39 volumio volumio[1106]: at onErrorNT (internal/child_process.js:472:16) Sep 04 11:49:39 volumio volumio[1106]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 04 11:49:39 volumio volumio[1106]: at runNextTicks (internal/process/task_queues.js:64:3) Sep 04 11:49:39 volumio volumio[1106]: at processTimers (internal/timers.js:497:9) { Sep 04 11:49:39 volumio volumio[1106]: errno: -2, Sep 04 11:49:39 volumio volumio[1106]: code: 'ENOENT', Sep 04 11:49:39 volumio volumio[1106]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 04 11:49:39 volumio volumio[1106]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 04 11:49:39 volumio volumio[1106]: spawnargs: [ Sep 04 11:49:39 volumio volumio[1106]: '-p', Sep 04 11:49:39 volumio volumio[1106]: 9876, Sep 04 11:49:39 volumio volumio[1106]: '-o', Sep 04 11:49:39 volumio volumio[1106]: '/tmp/camilladsp.log', Sep 04 11:49:39 volumio volumio[1106]: '-l', Sep 04 11:49:39 volumio volumio[1106]: 'warn', Sep 04 11:49:39 volumio volumio[1106]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 04 11:49:39 volumio volumio[1106]: ] Sep 04 11:49:39 volumio volumio[1106]: } Sep 04 11:49:39 volumio volumio[1106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 11:49:39 volumio sudo[6488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 11:48 Sep 04 11:49:39 volumio sudo[6488]: 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"