-- Logs begin at Thu 2019-02-14 20:11:58 AEST, end at Sat 2025-05-24 12:35:24 AEST. -- May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 12:34:01 volumio volumio[1088]: xcb_connection_has_error() returned true May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 12:34:01 volumio volumio[1088]: xcb_connection_has_error() returned true May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 24 12:34:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 12:34:38 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:34:38 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:34:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 12:34:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 24 12:34:47 volumio volumio[1088]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 24 12:34:51 volumio volumio[1088]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 24 12:34:51 volumio volumio[1088]: info: Folder /tmp/plugins removed May 24 12:34:51 volumio volumio[1088]: info: Check plugin dependencies May 24 12:34:51 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 12:34:51 volumio volumio[1088]: info: Checking if plugin already exists May 24 12:34:51 volumio volumio[1088]: info: Rename folder May 24 12:34:51 volumio volumio[1088]: info: Folder /tmp/downloaded_plugin.zip removed May 24 12:34:51 volumio volumio[1088]: info: Move to category May 24 12:34:52 volumio volumio[1088]: info: Checking if install.sh is present May 24 12:34:52 volumio volumio[1088]: info: Executing install.sh May 24 12:34:52 volumio sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 24 12:34:52 volumio sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:34:52 volumio volumio[1088]: info: creating filters folder and copying demo filters May 24 12:34:52 volumio volumio[1088]: info: copying demo flters May 24 12:34:52 volumio volumio[1088]: info: ./presets/ May 24 12:34:52 volumio volumio[1088]: info: ./presets/2XEQ15/ May 24 12:34:52 volumio volumio[1088]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/PEQ/ May 24 12:34:52 volumio sudo[3777]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 24 12:34:52 volumio sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:34:52 volumio volumio[1088]: info: ./presets/convfir/ May 24 12:34:52 volumio volumio[1088]: info: ./presets/convfir/kef.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/convfir/test.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/EQ15/ May 24 12:34:52 volumio volumio[1088]: info: ./presets/EQ15/.Bass-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/EQ15/.Classic-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/EQ15/.Voice-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: ./presets/EQ15/.Rock-FusionDsp.json May 24 12:34:52 volumio volumio[1088]: info: Installing/fusiondsp dependencies May 24 12:34:52 volumio volumio[1088]: info: Get:1 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] May 24 12:34:53 volumio kernel: hwmon hwmon3: Undervoltage detected! May 24 12:34:53 volumio volumio[1088]: info: Get:2 https://deb.nodesource.com/node_14.x buster/main armhf Packages [786 B] May 24 12:34:53 volumio volumio[1088]: info: Get:3 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] May 24 12:34:53 volumio volumio[1088]: info: Get:4 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] May 24 12:34:54 volumio volumio[1088]: info: Get:5 http://raspbian.raspberrypi.org/raspbian buster/main armhf Packages [13.0 MB] May 24 12:34:54 volumio volumio[1088]: info: Get:6 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] May 24 12:34:55 volumio kernel: hwmon hwmon3: Voltage normalised May 24 12:35:01 volumio volumio[1088]: info: Get:7 http://raspbian.raspberrypi.org/raspbian buster/contrib armhf Packages [58.8 kB] May 24 12:35:02 volumio volumio[1088]: info: Get:8 http://raspbian.raspberrypi.org/raspbian buster/non-free armhf Packages [110 kB] May 24 12:35:03 volumio kernel: hwmon hwmon3: Undervoltage detected! May 24 12:35:04 volumio volumio[1088]: info: Get:9 http://raspbian.raspberrypi.org/raspbian buster/rpi armhf Packages [1360 B] May 24 12:35:06 volumio volumio[1088]: info: Fetched 13.7 MB in 12s (1159 kB/s) May 24 12:35:07 volumio kernel: hwmon hwmon3: Voltage normalised May 24 12:35:10 volumio volumio[1088]: info: Reading package lists... May 24 12:35:10 volumio sudo[3777]: pam_unix(sudo:session): session closed for user root May 24 12:35:10 volumio sudo[4045]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 24 12:35:10 volumio sudo[4045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:10 volumio volumio[1088]: info: Reading package lists... May 24 12:35:10 volumio sudo[4045]: pam_unix(sudo:session): session closed for user root May 24 12:35:10 volumio sudo[4050]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 24 12:35:10 volumio sudo[4050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:10 volumio volumio[1088]: info: lib/systemd/system/fusiondsp.service May 24 12:35:10 volumio sudo[4050]: pam_unix(sudo:session): session closed for user root May 24 12:35:11 volumio volumio[1088]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 24 12:35:11 volumio volumio[1088]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 24 12:35:11 volumio volumio[1088]: info: May 24 12:35:11 volumio volumio[1088]: info: Cannot open cgui-1.0.0.zip or cgui-1.0.0.zip.zip May 24 12:35:11 volumio sudo[4055]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 24 12:35:11 volumio sudo[4055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:11 volumio sudo[4055]: pam_unix(sudo:session): session closed for user root May 24 12:35:11 volumio sudo[4057]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 24 12:35:11 volumio sudo[4057]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:11 volumio sudo[4057]: pam_unix(sudo:session): session closed for user root May 24 12:35:11 volumio sudo[4059]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 24 12:35:11 volumio sudo[4059]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:11 volumio sudo[4059]: pam_unix(sudo:session): session closed for user root May 24 12:35:11 volumio volumio[1088]: info: copying hw detection script May 24 12:35:11 volumio volumio[1088]: info: Detected cpu architecture as aarch64 May 24 12:35:14 volumio sudo[4086]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params May 24 12:35:14 volumio sudo[4086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:14 volumio sudo[4086]: pam_unix(sudo:session): session closed for user root May 24 12:35:14 volumio sudo[4088]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params May 24 12:35:14 volumio sudo[4088]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:14 volumio sudo[4088]: pam_unix(sudo:session): session closed for user root May 24 12:35:14 volumio sudo[4090]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 24 12:35:14 volumio sudo[4090]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:14 volumio volumio[1088]: info: Reading package lists... May 24 12:35:14 volumio sudo[4090]: pam_unix(sudo:session): session closed for user root May 24 12:35:14 volumio sudo[3752]: pam_unix(sudo:session): session closed for user root May 24 12:35:14 volumio volumio[1088]: info: Install script completed May 24 12:35:14 volumio volumio[1088]: info: Adding reference to registry May 24 12:35:14 volumio volumio[1088]: info: Done installing plugin. May 24 12:35:14 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 12:35:14 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 24 12:35:14 volumio volumio[1088]: Plugin install end detected on script May 24 12:35:14 volumio volumio[1088]: info: Folder /tmp/plugins removed May 24 12:35:14 volumio volumio[1088]: info: Folder /tmp/downloaded_plugin.zip removed May 24 12:35:14 volumio volumio[1088]: info: Folder /data/temp removed May 24 12:35:22 volumio volumio[1088]: info: Enabling plugin fusiondsp May 24 12:35:22 volumio volumio[1088]: info: Loading plugin "fusiondsp"... May 24 12:35:22 volumio volumio[1088]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json May 24 12:35:22 volumio volumio[1088]: info: Preparing to generate the ALSA configuration file May 24 12:35:22 volumio volumio[1088]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 24 12:35:22 volumio volumio[1088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 24 12:35:22 volumio volumio[1088]: info: Reading ALSA contributions from plugins. May 24 12:35:22 volumio volumio[1088]: error: Cannot write /etc/asound.conf: Error: ENOSPC: no space left on device, write May 24 12:35:22 volumio volumio[1088]: info: Output device has changed, restarting MPD May 24 12:35:22 volumio sudo[4111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 12:35:22 volumio sudo[4111]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4111]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio volumio[1088]: info: Output device has changed, restarting Shairport Sync May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 12:35:22 volumio sudo[4114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 12:35:22 volumio sudo[4114]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio systemd[1]: Stopping Music Player Daemon... May 24 12:35:22 volumio volumio[1088]: xcb_connection_has_error() returned true May 24 12:35:22 volumio systemd[1]: mpd.service: Succeeded. May 24 12:35:22 volumio volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 12:35:22 volumio volumio[1088]: xcb_connection_has_error() returned true May 24 12:35:22 volumio systemd[1]: Stopped Music Player Daemon. May 24 12:35:22 volumio volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:35:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:35:22 volumio vtcs[3284]: [2025-05-24 12:35:22.567] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 24 12:35:22 volumio vtcs[3284]: [2025-05-24 12:35:22.567] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 24 12:35:22 volumio volumio[1088]: info: PLUGIN START: fusiondsp May 24 12:35:22 volumio volumio[1088]: info: Loading i18n strings for locale en May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: FusionDsp - mixtype--------------------- Hardware May 24 12:35:22 volumio volumio[1088]: info: Preparing to generate the ALSA configuration file May 24 12:35:22 volumio volumio[1088]: info: Done. May 24 12:35:22 volumio sudo[4123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 12:35:22 volumio systemd[1]: Starting Music Player Daemon... May 24 12:35:22 volumio sudo[4123]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 12:35:22 volumio sudo[4126]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 24 12:35:22 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 24 12:35:22 volumio systemd[1]: vtcs.service: Succeeded. May 24 12:35:22 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 24 12:35:22 volumio sudo[4123]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio sudo[4119]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 12:35:22 volumio sudo[4119]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4119]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio sudo[4126]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio volumio[1088]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 24 12:35:22 volumio volumio[1088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 24 12:35:22 volumio volumio[1088]: info: Reading ALSA contributions from plugins. May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:22 volumio sudo[4134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 12:35:22 volumio sudo[4134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio volumio[1088]: info: MPD Permissions set May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio sudo[4134]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio sudo[4138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 24 12:35:22 volumio sudo[4138]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 24 12:35:22 volumio qobuz-connect[3244]: 20250524 12:35:22.665 [3244.3244] INFO SampleApp: Stopping Local configuration server May 24 12:35:22 volumio volumio[1088]: info: FusionDsp - May 24 12:35:22 volumio qobuz-connect[3244]: 20250524 12:35:22.676 [3244.3244] INFO SampleApp: shat down connection on UNIX socket May 24 12:35:22 volumio systemd[1]: qobuz-connect.service: Succeeded. May 24 12:35:22 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 12:35:22 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 24 12:35:22 volumio sudo[4138]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio volumio[1088]: info: FusionDsp - Error: TypeError: Cannot read property 'split' of undefined May 24 12:35:22 volumio volumio[1088]: info: FusionDsp - undefined May 24 12:35:22 volumio volumio[1088]: info: Starting Shairport Sync May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:35:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:35:22 volumio volumio[1088]: info: Executing endpoint qc_getconfig May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 12:35:22 volumio sudo[4147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 12:35:22 volumio sudo[4147]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.757 [4144.4144] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.759 [4144.4144] INFO VolumeManager: [0x125a3a0]: Setting new playback volume: 75 May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.759 [4144.4144] INFO VolumeManager: [0x125a3a0]: Setting new mute state: 0 May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.759 [4144.4144] INFO QobuzConnect: [0x125ad70]: Client initialized! May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.759 [4144.4144] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.762 [4144.4144] INFO LocalConfigManager: [0x1259c80]: Starting Local Configuration server May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.762 [4144.4144] INFO SampleApp: Starting Local configuration server May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.762 [4144.4144] INFO SampleApp: Connected to UNIX socket client 0x124ff60 May 24 12:35:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio systemd[1]: shairport-sync.service: Succeeded. May 24 12:35:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 12:35:22 volumio sudo[4147]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio volumio[1088]: info: Shairport-Sync Started May 24 12:35:22 volumio volumio[1088]: (node:1088) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 12:35:22 volumio volumio[1088]: (Use `node --trace-warnings ...` to show where the warning was created) May 24 12:35:22 volumio volumio[1088]: (node:1088) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 12:35:22 volumio volumio[1088]: (node:1088) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 12:35:22 volumio volumio[1088]: (node:1088) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 12:35:22 volumio volumio[1088]: (node:1088) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 12:35:22 volumio volumio[1088]: error: Cannot write /etc/asound.conf: Error: ENOSPC: no space left on device, write May 24 12:35:22 volumio volumio[1088]: info: Output device has changed, restarting MPD May 24 12:35:22 volumio sudo[4154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 12:35:22 volumio sudo[4154]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4154]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio volumio[1088]: info: Output device has changed, restarting Shairport Sync May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 12:35:22 volumio sudo[4157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 12:35:22 volumio sudo[4157]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 24 12:35:22 volumio systemd[1]: mpd.service: Succeeded. May 24 12:35:22 volumio systemd[1]: Stopped Music Player Daemon. May 24 12:35:22 volumio volumio[1088]: xcb_connection_has_error() returned true May 24 12:35:22 volumio volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 12:35:22 volumio volumio[1088]: xcb_connection_has_error() returned true May 24 12:35:22 volumio volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:35:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:35:22 volumio systemd[1]: Starting Music Player Daemon... May 24 12:35:22 volumio sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 12:35:22 volumio sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 12:35:22 volumio sudo[4170]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4167]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio sudo[4162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 12:35:22 volumio sudo[4162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio sudo[4162]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio sudo[4170]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.906 [4144.4144] INFO SampleApp: Playback volume changed: 75 May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio sudo[4180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 24 12:35:22 volumio sudo[4180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:35:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:35:22 volumio volumio[1088]: info: MPD Permissions set May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio sudo[4180]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:22 volumio sudo[4186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 24 12:35:22 volumio sudo[4186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio volumio[1088]: info: Starting Shairport Sync May 24 12:35:22 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.945 [4144.4144] INFO SampleApp: Stopping Local configuration server May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:22 volumio qobuz-connect[4144]: 20250524 12:35:22.956 [4144.4144] INFO SampleApp: shat down connection on UNIX socket May 24 12:35:22 volumio sudo[4189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 12:35:22 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked May 24 12:35:22 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:35:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:35:22 volumio sudo[4189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:22 volumio systemd[1]: qobuz-connect.service: Succeeded. May 24 12:35:22 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:22 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 24 12:35:22 volumio sudo[4186]: pam_unix(sudo:session): session closed for user root May 24 12:35:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 12:35:22 volumio systemd[1]: shairport-sync.service: Succeeded. May 24 12:35:22 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 12:35:23 volumio volumio[1088]: info: Executing endpoint qc_getconfig May 24 12:35:23 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.002 [4191.4191] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 12:35:23 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 12:35:23 volumio volumio[1088]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.003 [4191.4191] INFO VolumeManager: [0x11d83a0]: Setting new playback volume: 75 May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.003 [4191.4191] INFO VolumeManager: [0x11d83a0]: Setting new mute state: 0 May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.003 [4191.4191] INFO QobuzConnect: [0x11d8d70]: Client initialized! May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.003 [4191.4191] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 24 12:35:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 12:35:23 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 12:35:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 12:35:23 volumio sudo[4189]: pam_unix(sudo:session): session closed for user root May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.012 [4191.4191] INFO LocalConfigManager: [0x11d7c80]: Starting Local Configuration server May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.012 [4191.4191] INFO SampleApp: Starting Local configuration server May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.012 [4191.4191] INFO SampleApp: Connected to UNIX socket client 0x11cdf60 May 24 12:35:23 volumio volumio[1088]: info: Shairport-Sync Started May 24 12:35:23 volumio qobuz-connect[4191]: 20250524 12:35:23.152 [4191.4191] INFO SampleApp: Playback volume changed: 75 May 24 12:35:23 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 24 12:35:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 24 12:35:23 volumio mpd[4177]: May 24 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 12:35:23 volumio systemd[1]: Started Music Player Daemon. May 24 12:35:23 volumio sudo[4157]: pam_unix(sudo:session): session closed for user root May 24 12:35:23 volumio sudo[4114]: pam_unix(sudo:session): session closed for user root May 24 12:35:23 volumio volumio[1088]: error: MPD error: The expression evaluated to a falsy value: May 24 12:35:23 volumio volumio[1088]: assert.ok(self.idling) May 24 12:35:23 volumio volumio[1088]: error: The expression evaluated to a falsy value: May 24 12:35:23 volumio volumio[1088]: assert.ok(self.idling) May 24 12:35:23 volumio volumio[1088]: error: updateQueue error: null May 24 12:35:23 volumio kernel: hwmon hwmon3: Undervoltage detected! May 24 12:35:23 volumio volumio[1088]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' May 24 12:35:24 volumio volumio[1088]: info: camilladsp service started and running in background, instance 1 May 24 12:35:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 12:35:24 volumio volumio[1088]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent May 24 12:35:24 volumio volumio[1088]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 24 12:35:24 volumio volumio[1088]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent May 24 12:35:24 volumio volumio[1088]: info: FusionDsp loaded May 24 12:35:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 12:35:24 volumio sudo[4209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 24 12:35:24 volumio sudo[4209]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 12:35:24 volumio volumio[1088]: info: FusionDsp - Reporting Fusion DSP Enabled May 24 12:35:24 volumio volumio[1088]: info: Adding Signal Path Element [object Object] May 24 12:35:24 volumio volumio[1088]: info: Adding fusiondspeq DSP Signal Path Element May 24 12:35:24 volumio volumio[1088]: info: FusionDsp - ---- installed callbackRead May 24 12:35:24 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 12:35:24 volumio volumio[1088]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES May 24 12:35:24 volumio volumio[1088]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 24 12:35:24 volumio volumio[1088]: at onErrorNT (internal/child_process.js:465:16) May 24 12:35:24 volumio volumio[1088]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 24 12:35:24 volumio volumio[1088]: errno: -13, May 24 12:35:24 volumio volumio[1088]: code: 'EACCES', May 24 12:35:24 volumio volumio[1088]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 24 12:35:24 volumio volumio[1088]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 24 12:35:24 volumio volumio[1088]: spawnargs: [ May 24 12:35:24 volumio volumio[1088]: '-p', May 24 12:35:24 volumio volumio[1088]: 9876, May 24 12:35:24 volumio volumio[1088]: '-o', May 24 12:35:24 volumio volumio[1088]: '/tmp/camilladsp.log', May 24 12:35:24 volumio volumio[1088]: '-l', May 24 12:35:24 volumio volumio[1088]: 'warn', May 24 12:35:24 volumio volumio[1088]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 24 12:35:24 volumio volumio[1088]: ] May 24 12:35:24 volumio volumio[1088]: } May 24 12:35:24 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 12:35:24 volumio sudo[4209]: pam_unix(sudo:session): session closed for user root May 24 12:35:24 volumio sudo[4220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 12:34 May 24 12:35:24 volumio sudo[4220]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"