-- Logs begin at Wed 2026-04-15 17:43:13 CST, end at Wed 2026-04-15 21:21:56 CST. -- Apr 15 21:20:00 living-room volumio[3182]: info: Building dependency tree... Apr 15 21:20:00 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:00.561+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:00 living-room volumio[3182]: info: Reading state information... Apr 15 21:20:00 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:00.584+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:01 living-room volumio[3182]: info: The following NEW packages will be installed: Apr 15 21:20:01 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:01.886+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:01 living-room volumio[3182]: info: drc Apr 15 21:20:01 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:01.908+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:02 living-room volumio[3182]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Apr 15 21:20:02 living-room volumio[3182]: info: Need to get 118 kB of archives. Apr 15 21:20:02 living-room volumio[3182]: info: After this operation, 471 kB of additional disk space will be used. Apr 15 21:20:02 living-room volumio[3182]: info: Get:1 http://archive.debian.org/debian buster/main armhf drc armhf 3.2.2~dfsg0-2 [118 kB] Apr 15 21:20:02 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:02.124+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:02 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:02.132+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:02 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:02.133+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:02 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:02.136+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:03 living-room volumio[3182]: info: Fetched 118 kB in 0s (635 kB/s) Apr 15 21:20:03 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:03.661+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:03 living-room volumio[3182]: info: Selecting previously unselected package drc. Apr 15 21:20:03 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:03.754+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:04 living-room volumio[3182]: [620B blob data] Apr 15 21:20:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:04.517+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:04 living-room volumio[3182]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... Apr 15 21:20:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:04.535+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:04 living-room volumio[3182]: info: Unpacking drc (3.2.2~dfsg0-2) ... Apr 15 21:20:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:04.543+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:04 living-room volumio[3182]: info: Setting up drc (3.2.2~dfsg0-2) ... Apr 15 21:20:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:04.885+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:04 living-room volumio[3182]: info: Processing triggers for man-db (2.8.5-2) ... Apr 15 21:20:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:04.906+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:05 living-room sudo[12016]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:05 living-room volumio[3182]: Plugin install end detected on script Apr 15 21:20:05 living-room sudo[10265]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:05 living-room volumio[3182]: info: Install script completed Apr 15 21:20:05 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:05.909+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:05 living-room volumio[3182]: info: Adding reference to registry Apr 15 21:20:05 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:05.920+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:05 living-room volumio[3182]: info: Done installing plugin. Apr 15 21:20:05 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 21:20:05 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 21:20:05 living-room volumio[3182]: info: Folder /tmp/plugins removed Apr 15 21:20:05 living-room volumio[3182]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 21:20:05 living-room volumio[3182]: info: Folder /data/temp removed Apr 15 21:20:18 living-room go-librespot[3529]: time="2026-04-15T21:20:18+08:00" level=trace msg="sent dealer ping" Apr 15 21:20:18 living-room go-librespot[3529]: time="2026-04-15T21:20:18+08:00" level=trace msg="received dealer pong" Apr 15 21:20:44 living-room volumio[3182]: info: Enabling plugin fusiondsp Apr 15 21:20:44 living-room volumio[3182]: info: Loading plugin "fusiondsp"... Apr 15 21:20:45 living-room volumio[3182]: info: Preparing to generate the ALSA configuration file Apr 15 21:20:45 living-room volumio[3182]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 15 21:20:45 living-room volumio[3182]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 21:20:45 living-room volumio[3182]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 21:20:45 living-room volumio[3182]: info: Reading ALSA contributions from plugins. Apr 15 21:20:45 living-room volumio[3182]: info: Asound.conf file written Apr 15 21:20:45 living-room sudo[12156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 21:20:45 living-room sudo[12156]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:45 living-room sudo[12156]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:45 living-room kernel: Fine tdm clk setting range (0~2000000), 12287994 Apr 15 21:20:45 living-room kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Apr 15 21:20:45 living-room kernel: out of value, fixed it Apr 15 21:20:45 living-room kernel: id=0 set inskew=0 Apr 15 21:20:45 living-room volumio[3182]: No protocol specified Apr 15 21:20:45 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:45 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Apr 15 21:20:45 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Apr 15 21:20:45 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 15 21:20:45 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 15 21:20:45 living-room volumio[3182]: info: Output device has changed, restarting MPD Apr 15 21:20:45 living-room sudo[12161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 21:20:45 living-room sudo[12161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:45 living-room volumio[3182]: info: Output device has changed, restarting Shairport Sync Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:45 living-room sudo[12161]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:45 living-room sudo[12164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:45 living-room sudo[12164]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:45 living-room systemd[1]: Stopping Music Player Daemon... Apr 15 21:20:45 living-room volumio[3182]: No protocol specified Apr 15 21:20:45 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:45 living-room volumio[3182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 21:20:45 living-room volumio[3182]: No protocol specified Apr 15 21:20:45 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:45 living-room volumio[3182]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:45 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:45 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:45 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:45 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:45 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:45 living-room volumio[3182]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:45 living-room vtcs[8395]: [2026-04-15 21:20:45.881] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 15 21:20:45 living-room vtcs[8395]: [2026-04-15 21:20:45.886] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 15 21:20:45 living-room kernel: aml_spdif_open Apr 15 21:20:45 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:45 living-room kernel: spdif_a keep clk continuous Apr 15 21:20:45 living-room kernel: aml_spdif_close Apr 15 21:20:45 living-room kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 15 21:20:45 living-room volumio[3182]: info: PLUGIN START: fusiondsp Apr 15 21:20:45 living-room sudo[12181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:45 living-room volumio[3182]: info: Loading i18n strings for locale zh_TW Apr 15 21:20:45 living-room sudo[12181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:45 living-room volumio[3182]: info: Plugin fusiondsp has duplicated i18n key SAVE. It is ignored. Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 21:20:45 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:45 living-room volumio[3182]: info: FusionDsp - mixtype--------------------- Software Apr 15 21:20:45 living-room volumio[3182]: info: Preparing to generate the ALSA configuration file Apr 15 21:20:45 living-room systemd[1]: Stopping Volumio Tidal Connect Service... Apr 15 21:20:45 living-room systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:45 living-room systemd[1]: vtcs.service: Succeeded. Apr 15 21:20:45 living-room systemd[1]: Stopped Volumio Tidal Connect Service. Apr 15 21:20:45 living-room volumio[3182]: info: Done. Apr 15 21:20:45 living-room sudo[12181]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:45 living-room sudo[12169]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:46 living-room sudo[12169]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:46 living-room sudo[12169]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:46 living-room sudo[12186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:46 living-room sudo[12186]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:46 living-room sudo[12186]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:46 living-room volumio[3182]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 15 21:20:46 living-room volumio[3182]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 21:20:46 living-room volumio[3182]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 21:20:46 living-room volumio[3182]: info: Reading ALSA contributions from plugins. Apr 15 21:20:46 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:46 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:46 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:46 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:46 living-room sudo[12195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 15 21:20:46 living-room sudo[12195]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:20:46 living-room sudo[12195]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:46 living-room volumio[3182]: info: MPD Permissions set Apr 15 21:20:46 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:46 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:46 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:46 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:46 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.157+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.157+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.158+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.158+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:46 living-room sudo[12197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 15 21:20:46 living-room sudo[12197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:46 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:46 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:46 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:46 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:46 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.180+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.181+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.181+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:46.181+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:46 living-room volumio[3182]: info: Setting Spotify Volume from Volumio: 0 Apr 15 21:20:46 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:46 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:46 living-room systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:46 living-room qobuz-connect[8354]: 20260415 21:20:46.236 [8354.8354] INFO SampleApp: Stopping Local configuration server Apr 15 21:20:46 living-room qobuz-connect[8354]: 20260415 21:20:46.246 [8354.8354] INFO SampleApp: shat down connection on UNIX socket Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 21:20:46 living-room systemd[1]: qobuz-connect.service: Succeeded. Apr 15 21:20:46 living-room systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:46 living-room systemd[1]: Started Volumio Qobuz Connect Service. Apr 15 21:20:46 living-room sudo[12197]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:46 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:46 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:46 living-room volumio[3182]: info: FusionDsp - Apr 15 21:20:46 living-room volumio[3182]: info: FusionDsp - Apr 15 21:20:46 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 15 21:20:46 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:46 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:46 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: Not Reporting Auto name since its the default one Apr 15 21:20:47 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:20:47 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:20:47 living-room volumio[3182]: info: Enabling plugin fusiondsp Apr 15 21:20:47 living-room volumio[3182]: info: Loading plugin "fusiondsp"... Apr 15 21:20:47 living-room volumio[3182]: info: Applying required configuration parameters for plugin fusiondsp Apr 15 21:20:47 living-room volumio[3182]: info: Starting Shairport Sync Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:47 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::volumioPause Apr 15 21:20:47 living-room volumio[3182]: info: CoreStateMachine::pause Apr 15 21:20:47 living-room volumio[3182]: info: mpdhttpout --- Volumio set on pause Apr 15 21:20:47 living-room volumio[3182]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Apr 15 21:20:47 living-room sudo[12214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 21:20:47 living-room sudo[12214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room volumio[3182]: info: Executing endpoint qc_getconfig Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 15 21:20:47 living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 21:20:47 living-room systemd[1]: shairport-sync.service: Succeeded. Apr 15 21:20:47 living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:47 living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:47 living-room sudo[12214]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.360 [12205.12205] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 15 21:20:47 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:47 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:47 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 15 21:20:47 living-room volumio[3182]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.389 [12205.12205] INFO VolumeManager: [0xab850150]: Setting new playback volume: 75 Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.390 [12205.12205] INFO VolumeManager: [0xab850150]: Setting new mute state: 0 Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.390 [12205.12205] INFO AudioStreamManager: [0xab84fea8]: Setting new audio download buffer size: 1048576 Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.390 [12205.12205] INFO QobuzConnect: [0xab850a18]: Client initialized! Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.390 [12205.12205] INFO SampleApp: Starting Avahi advertising, name: Living Room, service name: _qobuz-connect._tcp Apr 15 21:20:47 living-room volumio[3182]: info: Shairport-Sync Started Apr 15 21:20:47 living-room volumio[3182]: info: Asound.conf file unchanged, so no further update is needed Apr 15 21:20:47 living-room volumio[3182]: info: Output device has changed, restarting MPD Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.405 [12205.12205] INFO LocalConfigManager: [0xab84fbd0]: Starting Local Configuration server Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.405 [12205.12205] INFO SampleApp: Starting Local configuration server Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.406 [12205.12205] INFO SampleApp: Connected to UNIX socket client 0xab845ed8 Apr 15 21:20:47 living-room sudo[12224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 21:20:47 living-room sudo[12224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room volumio[3182]: info: Output device has changed, restarting Shairport Sync Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:47 living-room sudo[12224]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room sudo[12227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:47 living-room sudo[12227]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.509 [12205.12205] INFO SampleApp: Playback volume changed: 75 Apr 15 21:20:47 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:47 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:47 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:47 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:47 living-room volumio[3182]: No protocol specified Apr 15 21:20:47 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:47 living-room volumio[3182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 21:20:47 living-room volumio[3182]: No protocol specified Apr 15 21:20:47 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:47 living-room volumio[3182]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:47 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:47 living-room volumio[3182]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:47 living-room kernel: aml_spdif_open Apr 15 21:20:47 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:47 living-room kernel: spdif_a keep clk continuous Apr 15 21:20:47 living-room kernel: aml_spdif_close Apr 15 21:20:47 living-room kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 15 21:20:47 living-room volumio[3182]: info: Preparing to generate the ALSA configuration file Apr 15 21:20:47 living-room sudo[12239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:47 living-room sudo[12239]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room sudo[12239]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room sudo[12233]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:47 living-room sudo[12233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room sudo[12233]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room sudo[12244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:47 living-room sudo[12244]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room sudo[12244]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 15 21:20:47 living-room sudo[12252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 15 21:20:47 living-room sudo[12252]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room sudo[12252]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room sudo[12256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 15 21:20:47 living-room sudo[12256]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:47 living-room volumio[3182]: No protocol specified Apr 15 21:20:47 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:47 living-room systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.910 [12205.12205] INFO SampleApp: Stopping Local configuration server Apr 15 21:20:47 living-room qobuz-connect[12205]: 20260415 21:20:47.920 [12205.12205] INFO SampleApp: shat down connection on UNIX socket Apr 15 21:20:47 living-room systemd[1]: qobuz-connect.service: Succeeded. Apr 15 21:20:47 living-room systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 15 21:20:47 living-room volumio[3182]: info: Sending Spotify command with payload to local API: /player/volume Apr 15 21:20:47 living-room systemd[1]: Started Volumio Qobuz Connect Service. Apr 15 21:20:47 living-room sudo[12256]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:47 living-room sudo[12259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 15 21:20:47 living-room sudo[12259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:48 living-room sudo[12259]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:48 living-room sudo[12265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 15 21:20:48 living-room sudo[12265]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:48 living-room systemd[1]: Stopping RAAT DAEMON... Apr 15 21:20:48 living-room systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:48 living-room systemd[1]: raat-daemon.service: Succeeded. Apr 15 21:20:48 living-room systemd[1]: Stopped RAAT DAEMON. Apr 15 21:20:48 living-room systemd[1]: Started RAAT DAEMON. Apr 15 21:20:48 living-room volumio[3182]: info: camilladsp service started and running in background, instance 1 Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room sudo[12265]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:48 living-room kernel: aml_spdif_open Apr 15 21:20:48 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:48 living-room kernel: spdif_a keep clk continuous Apr 15 21:20:48 living-room kernel: aml_spdif_close Apr 15 21:20:48 living-room kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 15 21:20:48 living-room kernel: aml_spdif_open Apr 15 21:20:48 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:48 living-room volumio[3182]: No protocol specified Apr 15 21:20:48 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:48 living-room volumio[3182]: cannot open device 'volumioHw': Device or resource busy Apr 15 21:20:48 living-room volumio[3182]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 15 21:20:48 living-room volumio[3182]: No protocol specified Apr 15 21:20:48 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:48 living-room volumio[3182]: cannot open device 'volumioHw': Device or resource busy Apr 15 21:20:48 living-room volumio[3182]: info: FusionDsp loaded Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 21:20:48 living-room sudo[12287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 15 21:20:48 living-room sudo[12287]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:48 living-room volumio[3182]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 15 21:20:48 living-room volumio[3182]: info: Adding Signal Path Element [object Object] Apr 15 21:20:48 living-room volumio[3182]: info: Adding fusiondspeq DSP Signal Path Element Apr 15 21:20:48 living-room volumio[3182]: info: Additional DSP elements updated Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:48 living-room volumio[3182]: info: FusionDsp - ---- installed callbackRead Apr 15 21:20:48 living-room volumio[3182]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 15 21:20:48 living-room volumio[3182]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 21:20:48 living-room volumio[3182]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 21:20:48 living-room volumio[3182]: info: Reading ALSA contributions from plugins. Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:48 living-room systemd[1]: Started FusionDsp Daemon. Apr 15 21:20:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:48 living-room sudo[12287]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:48 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:48 living-room go-librespot[3529]: time="2026-04-15T21:20:48+08:00" level=debug msg="update volume requested to 0/65535" Apr 15 21:20:48 living-room volumio[3182]: info: Raat Daemon started successfully Apr 15 21:20:48 living-room volumio[3182]: info: MPD Permissions set Apr 15 21:20:48 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 15 21:20:48 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:48 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:48 living-room volumio[3182]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 21:20:48 living-room go-librespot[3529]: time="2026-04-15T21:20:48+08:00" level=trace msg="sent dealer ping" Apr 15 21:20:48 living-room go-librespot[3529]: time="2026-04-15T21:20:48+08:00" level=trace msg="received dealer pong" Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: Not Reporting Auto name since its the default one Apr 15 21:20:48 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:48 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:48 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:48 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:48 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:48 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:48 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:48 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:48 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:48 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.527+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.527+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.528+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.528+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.533+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.534+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.534+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:48.534+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:48 living-room go-librespot[3529]: time="2026-04-15T21:20:48+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 15 21:20:48 living-room go-librespot[3529]: time="2026-04-15T21:20:48+08:00" level=trace msg="emitting websocket event: volume" Apr 15 21:20:48 living-room volumio[3182]: info: Executing endpoint qc_getconfig Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.635 [12260.12260] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.647 [12260.12260] INFO VolumeManager: [0xac26a150]: Setting new playback volume: 75 Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.647 [12260.12260] INFO VolumeManager: [0xac26a150]: Setting new mute state: 0 Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.647 [12260.12260] INFO AudioStreamManager: [0xac269ea8]: Setting new audio download buffer size: 1048576 Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.647 [12260.12260] INFO QobuzConnect: [0xac26aa18]: Client initialized! Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.647 [12260.12260] INFO SampleApp: Starting Avahi advertising, name: Living Room, service name: _qobuz-connect._tcp Apr 15 21:20:48 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:48 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.686 [12260.12260] INFO LocalConfigManager: [0xac269bd0]: Starting Local Configuration server Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.687 [12260.12260] INFO SampleApp: Starting Local configuration server Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.687 [12260.12260] INFO SampleApp: Connected to UNIX socket client 0xac25fed8 Apr 15 21:20:48 living-room volumio[3182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 15 21:20:48 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 15 21:20:48 living-room volumio[3182]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 15 21:20:48 living-room volumio[3182]: error: FusionDsp - WebSocket error: [object Object] Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room qobuz-connect[12260]: 20260415 21:20:48.784 [12260.12260] INFO SampleApp: Playback volume changed: 75 Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 15 21:20:48 living-room volumio[3182]: No protocol specified Apr 15 21:20:48 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:49 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:49 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:49 living-room volumio[3182]: info: Starting Shairport Sync Apr 15 21:20:49 living-room volumio[3182]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:49 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::volumioPause Apr 15 21:20:49 living-room volumio[3182]: info: CoreStateMachine::pause Apr 15 21:20:49 living-room volumio[3182]: info: mpdhttpout --- Volumio set on pause Apr 15 21:20:49 living-room sudo[12309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 15 21:20:49 living-room sudo[12309]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room sudo[12311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 21:20:49 living-room sudo[12311]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:49 living-room sudo[12309]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:49 living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 21:20:49 living-room systemd[1]: shairport-sync.service: Succeeded. Apr 15 21:20:49 living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:49 living-room sudo[12314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 15 21:20:49 living-room sudo[12314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:49 living-room volumio[3182]: info: Asound.conf file unchanged, so no further update is needed Apr 15 21:20:49 living-room volumio[3182]: info: Output device has changed, restarting MPD Apr 15 21:20:49 living-room sudo[12311]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:49 living-room systemd[1]: Stopping RAAT DAEMON... Apr 15 21:20:49 living-room kernel: spdif_a keep clk continuous Apr 15 21:20:49 living-room kernel: aml_spdif_close Apr 15 21:20:49 living-room kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 15 21:20:49 living-room systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:49 living-room systemd[1]: raat-daemon.service: Succeeded. Apr 15 21:20:49 living-room systemd[1]: Stopped RAAT DAEMON. Apr 15 21:20:49 living-room systemd[1]: Started RAAT DAEMON. Apr 15 21:20:49 living-room sudo[12314]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:49 living-room sudo[12324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 21:20:49 living-room sudo[12324]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room kernel: aml_spdif_open Apr 15 21:20:49 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:49 living-room kernel: spdif_a keep clk continuous Apr 15 21:20:49 living-room kernel: aml_spdif_close Apr 15 21:20:49 living-room kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 15 21:20:49 living-room kernel: aml_spdif_open Apr 15 21:20:49 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:49 living-room volumio[3182]: info: Output device has changed, restarting Shairport Sync Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:49 living-room sudo[12324]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:49 living-room sudo[12335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:49 living-room sudo[12335]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:49 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:49 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:49 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:49 living-room volumio[3182]: No protocol specified Apr 15 21:20:49 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:49 living-room volumio[3182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 21:20:49 living-room volumio[3182]: No protocol specified Apr 15 21:20:49 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:49 living-room volumio[3182]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:49 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:49 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:49 living-room volumio[3182]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:49 living-room volumio[3182]: info: PLUGIN START: fusiondsp Apr 15 21:20:49 living-room volumio[3182]: info: Loading i18n strings for locale zh_TW Apr 15 21:20:49 living-room volumio[3182]: info: Plugin fusiondsp has duplicated i18n key SAVE. It is ignored. Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 21:20:49 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:49 living-room volumio[3182]: info: FusionDsp - mixtype--------------------- Software Apr 15 21:20:49 living-room volumio[3182]: info: Preparing to generate the ALSA configuration file Apr 15 21:20:49 living-room sudo[12346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:49 living-room sudo[12346]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room sudo[12342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:49 living-room sudo[12342]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:49 living-room sudo[12342]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:49 living-room volumio[3182]: info: Done. Apr 15 21:20:49 living-room sudo[12346]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:49 living-room sudo[12354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:49 living-room sudo[12354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room sudo[12354]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room sudo[12360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 15 21:20:50 living-room sudo[12360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 15 21:20:50 living-room sudo[12360]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:50 living-room sudo[12364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 15 21:20:50 living-room sudo[12364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:50 living-room sudo[12365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:50 living-room sudo[12365]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:50 living-room volumio[3182]: 0+0 records in Apr 15 21:20:50 living-room volumio[3182]: 0+0 records out Apr 15 21:20:50 living-room volumio[3182]: 0 bytes copied, 0.000242406 s, 0.0 kB/s Apr 15 21:20:50 living-room systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 15 21:20:50 living-room qobuz-connect[12260]: 20260415 21:20:50.218 [12260.12260] INFO SampleApp: Stopping Local configuration server Apr 15 21:20:50 living-room qobuz-connect[12260]: 20260415 21:20:50.228 [12260.12260] INFO SampleApp: shat down connection on UNIX socket Apr 15 21:20:50 living-room systemd[1]: qobuz-connect.service: Succeeded. Apr 15 21:20:50 living-room systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 15 21:20:50 living-room volumio[3182]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 15 21:20:50 living-room volumio[3182]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 21:20:50 living-room volumio[3182]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 21:20:50 living-room volumio[3182]: info: Reading ALSA contributions from plugins. Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room systemd[1]: Started Volumio Qobuz Connect Service. Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room sudo[12364]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:20:50 living-room volumio[3182]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 15 21:20:50 living-room volumio[3182]: info: MPD Permissions set Apr 15 21:20:50 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 15 21:20:50 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:50 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:50 living-room volumio[3182]: info: Raat Daemon started successfully Apr 15 21:20:50 living-room volumio[3182]: info: Shairport-Sync Started Apr 15 21:20:50 living-room volumio[3182]: (node:3182) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 15 21:20:50 living-room volumio[3182]: (node:3182) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 15 21:20:50 living-room volumio[3182]: (node:3182) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 15 21:20:50 living-room volumio[3182]: (node:3182) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 15 21:20:50 living-room volumio[3182]: (node:3182) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: Not Reporting Auto name since its the default one Apr 15 21:20:50 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:50 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:50 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:50 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:50 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:50 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:50 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:50 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:50 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:50 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:50 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:50 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:50 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:50 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.507+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.507+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.508+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.508+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.510+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.513+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.514+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.514+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:50 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:50 living-room volumio[3182]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:50 living-room volumio[3182]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:50 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:50 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:50 living-room volumio[3182]: info: Executing endpoint qc_getconfig Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 15 21:20:50 living-room sudo[12390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 21:20:50 living-room sudo[12390]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:50 living-room kernel: spdif_a keep clk continuous Apr 15 21:20:50 living-room kernel: aml_spdif_close Apr 15 21:20:50 living-room kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.657 [12377.12377] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 15 21:20:50 living-room sudo[12388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:50 living-room systemd[1]: Started Volumio Tidal Connect Service. Apr 15 21:20:50 living-room sudo[12388]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.681 [12377.12377] INFO VolumeManager: [0xac35d150]: Setting new playback volume: 75 Apr 15 21:20:50 living-room sudo[12390]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.682 [12377.12377] INFO VolumeManager: [0xac35d150]: Setting new mute state: 0 Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.683 [12377.12377] INFO AudioStreamManager: [0xac35cea8]: Setting new audio download buffer size: 1048576 Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.683 [12377.12377] INFO QobuzConnect: [0xac35da18]: Client initialized! Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.683 [12377.12377] INFO SampleApp: Starting Avahi advertising, name: Living Room, service name: _qobuz-connect._tcp Apr 15 21:20:50 living-room sudo[12388]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.718 [12377.12377] INFO LocalConfigManager: [0xac35cbd0]: Starting Local Configuration server Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.718 [12377.12377] INFO SampleApp: Starting Local configuration server Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.719 [12377.12377] INFO SampleApp: Connected to UNIX socket client 0xac352ed8 Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 21:20:50 living-room qobuz-connect[12377]: 20260415 21:20:50.807 [12377.12377] INFO SampleApp: Playback volume changed: 75 Apr 15 21:20:50 living-room kernel: aml_spdif_open Apr 15 21:20:50 living-room kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Apr 15 21:20:50 living-room kernel: set normal 512 fs /4 fs Apr 15 21:20:50 living-room kernel: set spdifout clk:5644800, mpll:22579200 Apr 15 21:20:50 living-room kernel: get spdifout clk:5644797, mpll:22579186 Apr 15 21:20:50 living-room kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Apr 15 21:20:50 living-room kernel: set normal 512 fs /4 fs Apr 15 21:20:50 living-room kernel: set spdifout clk:5644800, mpll:22579200 Apr 15 21:20:50 living-room kernel: get spdifout clk:5644797, mpll:22579186 Apr 15 21:20:50 living-room kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 15 21:20:50 living-room kernel: hdmitx: hw: set audio Apr 15 21:20:50 living-room kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Apr 15 21:20:50 living-room kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 Apr 15 21:20:50 living-room kernel: hdmitx: hw: aud_n_para = 8918 Apr 15 21:20:50 living-room kernel: hdmitx: hw: set channel status Apr 15 21:20:50 living-room kernel: hdmitx: audio: Audio Type: PCM Apr 15 21:20:50 living-room kernel: hdmitx: audio: set audio param Apr 15 21:20:50 living-room kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:50 living-room volumio[3182]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Apr 15 21:20:50 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:50.894+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:50 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 15 21:20:50 living-room volumio[3182]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 15 21:20:50 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:50 living-room volumio[3182]: info: RAAT: Requesting Headphone Status Apr 15 21:20:50 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 15 21:20:50 living-room volumio[3182]: info: FusionDsp - Apr 15 21:20:51 living-room volumio[3182]: info: FusionDsp - Apr 15 21:20:51 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 15 21:20:51 living-room volumio[3182]: No protocol specified Apr 15 21:20:51 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:51 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:51 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:51 living-room sudo[12482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 15 21:20:51 living-room volumio[3182]: info: Executing endpoint restartRAATSocket Apr 15 21:20:51 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 15 21:20:51 living-room sudo[12482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:51 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:51 living-room sudo[12482]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:51 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 15 21:20:51 living-room sudo[12486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 15 21:20:51 living-room sudo[12486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:51 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:20:51 living-room systemd[1]: Stopping RAAT DAEMON... Apr 15 21:20:51 living-room systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:51 living-room systemd[1]: raat-daemon.service: Succeeded. Apr 15 21:20:51 living-room sudo[12492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:51 living-room systemd[1]: Stopped RAAT DAEMON. Apr 15 21:20:51 living-room sudo[12492]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:51 living-room systemd[1]: Started RAAT DAEMON. Apr 15 21:20:51 living-room sudo[12486]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:51 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:20:51 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:51 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:51 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:51 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:51 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:51 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:51 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:51 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:51 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:51 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:51 living-room volumio[3182]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 15 21:20:51 living-room volumio[3182]: 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 Apr 15 21:20:51 living-room volumio[3182]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 15 21:20:51 living-room sudo[12499]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:51 living-room sudo[12499]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:51 living-room sudo[12499]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:51 living-room volumio[3182]: info: camilladsp service started and running in background, instance 2 Apr 15 21:20:51 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: No protocol specified Apr 15 21:20:52 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:52 living-room volumio[3182]: cannot open device 'volumioHw': Device or resource busy Apr 15 21:20:52 living-room volumio[3182]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 15 21:20:52 living-room volumio[3182]: No protocol specified Apr 15 21:20:52 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:52 living-room volumio[3182]: cannot open device 'volumioHw': Device or resource busy Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp loaded Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 15 21:20:52 living-room volumio[3182]: info: Adding Signal Path Element [object Object] Apr 15 21:20:52 living-room volumio[3182]: info: Updating fusiondspeq DSP Signal Path Element Apr 15 21:20:52 living-room volumio[3182]: info: Additional DSP elements updated Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp - ---- installed callbackRead Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 21:20:52 living-room volumio[3182]: info: Starting Shairport Sync Apr 15 21:20:52 living-room sudo[12544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 15 21:20:52 living-room sudo[12544]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room volumio[3182]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:52 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::volumioPause Apr 15 21:20:52 living-room volumio[3182]: info: CoreStateMachine::pause Apr 15 21:20:52 living-room volumio[3182]: info: mpdhttpout --- Volumio set on pause Apr 15 21:20:52 living-room volumio[3182]: info: MCU Signalled Headphone Mode Disabled Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 15 21:20:52 living-room volumio[3182]: info: Reporting Headphone State: false Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:52 living-room sudo[12544]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room sudo[12547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 21:20:52 living-room sudo[12547]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp - ---- read samplerate, raw: Apr 15 21:20:52 living-room volumio[3182]: error: FusionDsp - invalid sample rate Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp - ---- read samplerate, raw: Apr 15 21:20:52 living-room volumio[3182]: error: FusionDsp - invalid sample rate Apr 15 21:20:52 living-room volumio[3182]: info: Executing endpoint tc_getconfig Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 15 21:20:52 living-room vtcs[12398]: STARTING TidalConnect services, version: 1.6.1 Apr 15 21:20:52 living-room vtcs[12398]: STARTED TidalConnect services. Apr 15 21:20:52 living-room volumio[3182]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:52 living-room volumio[3182]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:52 living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 21:20:52 living-room systemd[1]: shairport-sync.service: Succeeded. Apr 15 21:20:52 living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:52 living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:52 living-room sudo[12547]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room volumio[3182]: info: Raat Daemon started successfully Apr 15 21:20:52 living-room volumio[3182]: info: RAAT: Requesting Headphone Status Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 15 21:20:52 living-room sudo[12559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 21:20:52 living-room sudo[12559]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:52 living-room volumio[3182]: info: Executing endpoint restartRAATSocket Apr 15 21:20:52 living-room sudo[12559]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 15 21:20:52 living-room volumio[3182]: info: Shairport-Sync Started Apr 15 21:20:52 living-room volumio[3182]: info: Asound.conf file unchanged, so no further update is needed Apr 15 21:20:52 living-room volumio[3182]: info: Output device has changed, restarting MPD Apr 15 21:20:52 living-room sudo[12573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 21:20:52 living-room sudo[12573]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room sudo[12573]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room volumio[3182]: info: Output device has changed, restarting Shairport Sync Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:52 living-room sudo[12576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:52 living-room sudo[12576]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:52 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:52 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:52 living-room volumio[3182]: No protocol specified Apr 15 21:20:52 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:52 living-room volumio[3182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 21:20:52 living-room volumio[3182]: No protocol specified Apr 15 21:20:52 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:52 living-room volumio[3182]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:52 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:52 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:52 living-room volumio[3182]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: info: Preparing to generate the ALSA configuration file Apr 15 21:20:52 living-room sudo[12585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:52 living-room sudo[12585]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room systemd[1]: Stopping Volumio Tidal Connect Service... Apr 15 21:20:52 living-room systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:52 living-room systemd[1]: vtcs.service: Succeeded. Apr 15 21:20:52 living-room systemd[1]: Stopped Volumio Tidal Connect Service. Apr 15 21:20:52 living-room sudo[12585]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room sudo[12581]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:52 living-room sudo[12581]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room sudo[12581]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room sudo[12589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:52 living-room sudo[12589]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room sudo[12589]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:52 living-room sudo[12599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 15 21:20:52 living-room sudo[12599]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room volumio[3182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 15 21:20:52 living-room volumio[3182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 15 21:20:52 living-room volumio[3182]: info: Executing endpoint tc_connect Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 15 21:20:52 living-room volumio[3182]: info: Connecting to TidalConnect Apr 15 21:20:52 living-room volumio[3182]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 15 21:20:52 living-room volumio[3182]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 21:20:52 living-room sudo[12599]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room volumio[3182]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 21:20:52 living-room volumio[3182]: info: Reading ALSA contributions from plugins. Apr 15 21:20:52 living-room volumio[3182]: info: MCU Signalled Headphone Mode Disabled Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 15 21:20:52 living-room volumio[3182]: info: Reporting Headphone State: false Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:52 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:52 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:52 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:52 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:52 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:52 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:52 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:52.830+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:52 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:52.831+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:52 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:52.831+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:52 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:52.831+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:52 living-room sudo[12601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 15 21:20:52 living-room volumio[3182]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 15 21:20:52 living-room sudo[12601]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:52 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:52 living-room qobuz-connect[12377]: 20260415 21:20:52.878 [12377.12377] INFO SampleApp: Stopping Local configuration server Apr 15 21:20:52 living-room volumio[3182]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 15 21:20:52 living-room volumio[3182]: info: MPD Permissions set Apr 15 21:20:52 living-room systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 15 21:20:52 living-room qobuz-connect[12377]: 20260415 21:20:52.889 [12377.12377] INFO SampleApp: shat down connection on UNIX socket Apr 15 21:20:52 living-room systemd[1]: qobuz-connect.service: Succeeded. Apr 15 21:20:52 living-room systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 15 21:20:52 living-room systemd[1]: Started Volumio Qobuz Connect Service. Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:52 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:52 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 15 21:20:52 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:52 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:52 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:52 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:52 living-room sudo[12601]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:52 living-room volumio[3182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:53 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:53 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:53 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:53 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:53 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:53.123+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:53 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:53.124+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:53 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:53.124+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:53 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:53.126+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 21:20:53 living-room volumio[3182]: info: Not Reporting Auto name since its the default one Apr 15 21:20:53 living-room volumio[3182]: info: Executing endpoint qc_getconfig Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.276 [12604.12604] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.283 [12604.12604] INFO VolumeManager: [0xac624150]: Setting new playback volume: 75 Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.283 [12604.12604] INFO VolumeManager: [0xac624150]: Setting new mute state: 0 Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.283 [12604.12604] INFO AudioStreamManager: [0xac623ea8]: Setting new audio download buffer size: 1048576 Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.283 [12604.12604] INFO QobuzConnect: [0xac624a18]: Client initialized! Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.283 [12604.12604] INFO SampleApp: Starting Avahi advertising, name: Living Room, service name: _qobuz-connect._tcp Apr 15 21:20:53 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.299 [12604.12604] INFO LocalConfigManager: [0xac623bd0]: Starting Local Configuration server Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.299 [12604.12604] INFO SampleApp: Starting Local configuration server Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.299 [12604.12604] INFO SampleApp: Connected to UNIX socket client 0xac619ed8 Apr 15 21:20:53 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 15 21:20:53 living-room volumio[3182]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 15 21:20:53 living-room qobuz-connect[12604]: 20260415 21:20:53.426 [12604.12604] INFO SampleApp: Playback volume changed: 75 Apr 15 21:20:53 living-room volumio[3182]: info: Starting Shairport Sync Apr 15 21:20:53 living-room volumio[3182]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:53 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::volumioPause Apr 15 21:20:53 living-room volumio[3182]: info: CoreStateMachine::pause Apr 15 21:20:53 living-room volumio[3182]: info: mpdhttpout --- Volumio set on pause Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:53 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:53 living-room sudo[12644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 21:20:53 living-room sudo[12644]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:53 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 15 21:20:53 living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 21:20:53 living-room systemd[1]: shairport-sync.service: Succeeded. Apr 15 21:20:53 living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:53 living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:53 living-room sudo[12644]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:53 living-room volumio[3182]: No protocol specified Apr 15 21:20:53 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:53 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:53 living-room volumio[3182]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:53 living-room volumio[3182]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:53 living-room sudo[12659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 15 21:20:53 living-room sudo[12659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:53 living-room sudo[12659]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:53 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:53 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:53 living-room volumio[3182]: info: TidalConnect service started! Apr 15 21:20:53 living-room volumio[3182]: info: Shairport-Sync Started Apr 15 21:20:53 living-room sudo[12663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 15 21:20:53 living-room sudo[12663]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:53 living-room sudo[12662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 21:20:53 living-room sudo[12662]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:53 living-room systemd[1]: Stopping RAAT DAEMON... Apr 15 21:20:53 living-room systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:53 living-room systemd[1]: raat-daemon.service: Succeeded. Apr 15 21:20:53 living-room systemd[1]: Stopped RAAT DAEMON. Apr 15 21:20:53 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:53 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:53 living-room volumio[3182]: info: Asound.conf file written Apr 15 21:20:53 living-room systemd[1]: Started RAAT DAEMON. Apr 15 21:20:53 living-room sudo[12663]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:53 living-room sudo[12675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 21:20:53 living-room sudo[12675]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:53 living-room systemd[1]: Started Volumio Tidal Connect Service. Apr 15 21:20:53 living-room sudo[12662]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room sudo[12675]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room kernel: Fine tdm clk setting range (0~2000000), 12287994 Apr 15 21:20:54 living-room kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Apr 15 21:20:54 living-room kernel: out of value, fixed it Apr 15 21:20:54 living-room kernel: id=0 set inskew=0 Apr 15 21:20:54 living-room volumio[3182]: No protocol specified Apr 15 21:20:54 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:54 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Apr 15 21:20:54 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Apr 15 21:20:54 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 15 21:20:54 living-room volumio[3182]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 15 21:20:54 living-room volumio[3182]: info: Output device has changed, restarting MPD Apr 15 21:20:54 living-room sudo[12693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 21:20:54 living-room sudo[12693]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room sudo[12693]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room volumio[3182]: info: Output device has changed, restarting Shairport Sync Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:54 living-room sudo[12696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:54 living-room sudo[12696]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:54 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:54 living-room volumio[3182]: No protocol specified Apr 15 21:20:54 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:54 living-room volumio[3182]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 21:20:54 living-room volumio[3182]: No protocol specified Apr 15 21:20:54 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:54 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:54 living-room volumio[3182]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:54 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room sudo[12706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:54 living-room sudo[12706]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room systemd[1]: Stopping Volumio Tidal Connect Service... Apr 15 21:20:54 living-room systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:54 living-room systemd[1]: vtcs.service: Succeeded. Apr 15 21:20:54 living-room systemd[1]: Stopped Volumio Tidal Connect Service. Apr 15 21:20:54 living-room sudo[12709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 21:20:54 living-room sudo[12709]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room sudo[12701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:54 living-room sudo[12701]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room sudo[12706]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room sudo[12701]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room sudo[12709]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 15 21:20:54 living-room sudo[12718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 15 21:20:54 living-room sudo[12718]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room volumio[3182]: info: RAAT: Requesting Headphone Status Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 15 21:20:54 living-room sudo[12718]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:54 living-room sudo[12722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:54 living-room sudo[12722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room volumio[3182]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 15 21:20:54 living-room volumio[3182]: info: MPD Permissions set Apr 15 21:20:54 living-room volumio[3182]: info: Raat Daemon started successfully Apr 15 21:20:54 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:54 living-room sudo[12723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:54 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:54 living-room sudo[12723]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.604+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.605+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.605+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.606+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:54 living-room volumio[3182]: info: Executing endpoint restartRAATSocket Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 15 21:20:54 living-room volumio[3182]: info: Executing endpoint tc_getconfig Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 15 21:20:54 living-room qobuz-connect[12604]: 20260415 21:20:54.673 [12604.12604] INFO SampleApp: Stopping Local configuration server Apr 15 21:20:54 living-room systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 15 21:20:54 living-room qobuz-connect[12604]: 20260415 21:20:54.684 [12604.12604] INFO SampleApp: shat down connection on UNIX socket Apr 15 21:20:54 living-room systemd[1]: qobuz-connect.service: Succeeded. Apr 15 21:20:54 living-room systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.759+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.759+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:54 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:54 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:54 living-room volumio[3182]: info: MCU Signalled Headphone Mode Disabled Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 15 21:20:54 living-room volumio[3182]: info: Reporting Headphone State: false Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:54 living-room sudo[12723]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:54 living-room systemd[1]: Started Volumio Qobuz Connect Service. Apr 15 21:20:54 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.765+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:54 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:54.765+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:54 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:54 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:54 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:54 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:54 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 15 21:20:54 living-room volumio[3182]: info: QobuzConnect: setDeactiveState invoked Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:54 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 21:20:54 living-room volumio[3182]: info: Not Reporting Auto name since its the default one Apr 15 21:20:54 living-room sudo[12735]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:54 living-room sudo[12735]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:54 living-room sudo[12735]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:55 living-room volumio[3182]: info: Executing endpoint qc_getconfig Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.063 [12734.12734] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 15 21:20:55 living-room volumio[3182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.070 [12734.12734] INFO VolumeManager: [0xaad65150]: Setting new playback volume: 75 Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.071 [12734.12734] INFO VolumeManager: [0xaad65150]: Setting new mute state: 0 Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.071 [12734.12734] INFO AudioStreamManager: [0xaad64ea8]: Setting new audio download buffer size: 1048576 Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.071 [12734.12734] INFO QobuzConnect: [0xaad65a18]: Client initialized! Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.071 [12734.12734] INFO SampleApp: Starting Avahi advertising, name: Living Room, service name: _qobuz-connect._tcp Apr 15 21:20:55 living-room volumio[3182]: info: Starting Shairport Sync Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.088 [12734.12734] INFO LocalConfigManager: [0xaad64bd0]: Starting Local Configuration server Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.088 [12734.12734] INFO SampleApp: Starting Local configuration server Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.089 [12734.12734] INFO SampleApp: Connected to UNIX socket client 0xaad5aed8 Apr 15 21:20:55 living-room volumio[3182]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:55 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::volumioPause Apr 15 21:20:55 living-room volumio[3182]: info: CoreStateMachine::pause Apr 15 21:20:55 living-room volumio[3182]: info: mpdhttpout --- Volumio set on pause Apr 15 21:20:55 living-room volumio[3182]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 15 21:20:55 living-room volumio[3182]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 15 21:20:55 living-room sudo[12760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 21:20:55 living-room sudo[12760]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:55 living-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 21:20:55 living-room systemd[1]: shairport-sync.service: Succeeded. Apr 15 21:20:55 living-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:55 living-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 21:20:55 living-room volumio[3182]: No protocol specified Apr 15 21:20:55 living-room volumio[3182]: xcb_connection_has_error() returned true Apr 15 21:20:55 living-room qobuz-connect[12734]: 20260415 21:20:55.214 [12734.12734] INFO SampleApp: Playback volume changed: 75 Apr 15 21:20:55 living-room sudo[12760]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:55 living-room sudo[12769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 15 21:20:55 living-room sudo[12769]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:55 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:55 living-room volumio[3182]: info: TidalConnect service started! Apr 15 21:20:55 living-room volumio[3182]: info: Shairport-Sync Started Apr 15 21:20:55 living-room sudo[12769]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:55 living-room sudo[12779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 15 21:20:55 living-room sudo[12779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:55 living-room systemd[1]: Stopping RAAT DAEMON... Apr 15 21:20:55 living-room systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:55 living-room systemd[1]: raat-daemon.service: Succeeded. Apr 15 21:20:55 living-room systemd[1]: Stopped RAAT DAEMON. Apr 15 21:20:55 living-room systemd[1]: Started RAAT DAEMON. Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:55 living-room volumio[3182]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Apr 15 21:20:55 living-room volumio[3182]: info: FusionDsp - Stopping FusionDsp service Apr 15 21:20:55 living-room volumio[3182]: info: camilladsp stopping service pid 12782... Apr 15 21:20:55 living-room sudo[12779]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:55 living-room volumio[3182]: info: camilladsp service terminated, instance 2 Apr 15 21:20:55 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:55.607+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:55 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:55.614+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:55 living-room sudo[12801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Apr 15 21:20:55 living-room sudo[12801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 15 21:20:55 living-room volumio[12289]: Backend configuration: Apr 15 21:20:55 living-room volumio[12289]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 15 21:20:55 living-room volumio[12289]: active_config_txt: null Apr 15 21:20:55 living-room volumio[12289]: camilla_host: 127.0.0.1 Apr 15 21:20:55 living-room volumio[12289]: camilla_port: 9876 Apr 15 21:20:55 living-room volumio[12289]: coeff_dir: /data/INTERNAL/FusionDsp/filters Apr 15 21:20:55 living-room volumio[12289]: config_dir: /data/configuration/audio_interface/fusiondsp Apr 15 21:20:55 living-room volumio[12289]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 15 21:20:55 living-room volumio[12289]: log_file: /tmp/camilladsp.log Apr 15 21:20:55 living-room volumio[12289]: on_get_active_config: null Apr 15 21:20:55 living-room volumio[12289]: on_set_active_config: null Apr 15 21:20:55 living-room volumio[12289]: port: 5011 Apr 15 21:20:55 living-room volumio[12289]: supported_capture_types: null Apr 15 21:20:55 living-room volumio[12289]: supported_playback_types: null Apr 15 21:20:55 living-room volumio[12289]: update_config_symlink: false Apr 15 21:20:55 living-room volumio[12289]: update_config_txt: false Apr 15 21:20:55 living-room volumio[12289]: ======== Running on http://0.0.0.0:5011 ======== Apr 15 21:20:55 living-room volumio[12289]: (Press CTRL+C to quit) Apr 15 21:20:55 living-room systemd[1]: Stopping FusionDsp Daemon... Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:55 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:55 living-room volumio[3182]: info: Raat Daemon started successfully Apr 15 21:20:55 living-room sudo[12808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:55 living-room sudo[12808]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:55 living-room volumio[3182]: info: Executing endpoint restartRAATSocket Apr 15 21:20:55 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 15 21:20:55 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:55 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:55 living-room systemd[1]: fusiondsp.service: Succeeded. Apr 15 21:20:55 living-room systemd[1]: Stopped FusionDsp Daemon. Apr 15 21:20:55 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:55 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:55 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:55 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:55 living-room sudo[12801]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:55 living-room volumio[3182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Apr 15 21:20:56 living-room volumio[3182]: info: FusionDsp - Reporting Fusion DSP Disabled Apr 15 21:20:56 living-room volumio[3182]: info: Removing fusiondspeq DSP Signal Path Element Apr 15 21:20:56 living-room volumio[3182]: info: Additional DSP elements updated Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:56 living-room volumio[3182]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:56 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:56 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:56 living-room sudo[12821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 21:20:56 living-room sudo[12821]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:56 living-room systemd[1]: Started Volumio Tidal Connect Service. Apr 15 21:20:56 living-room sudo[12821]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:56 living-room sudo[12819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:56 living-room sudo[12819]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:56 living-room sudo[12819]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.387+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:56 living-room volumio[3182]: info: Check plugin dependencies Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.460+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:56 living-room volumio[3182]: info: Rename folder Apr 15 21:20:56 living-room volumio[3182]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 21:20:56 living-room volumio[3182]: info: Executing endpoint tc_getconfig Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 15 21:20:56 living-room vtcs[12823]: STARTING TidalConnect services, version: 1.6.1 Apr 15 21:20:56 living-room vtcs[12823]: STARTED TidalConnect services. Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.533+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:56 living-room volumio[3182]: info: Move to category Apr 15 21:20:56 living-room volumio[3182]: info: Executing endpoint tc_connect Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 15 21:20:56 living-room volumio[3182]: info: Connecting to TidalConnect Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::servicePushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 15 21:20:56 living-room volumio[3182]: verbose: CURRENT POSITION 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::syncState stateService stop Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::syncState currentStatus stop Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:56 living-room volumio[3182]: info: No code Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::servicePushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 15 21:20:56 living-room volumio[3182]: verbose: CURRENT POSITION 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::syncState stateService stop Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::syncState currentStatus stop Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:56 living-room volumio[3182]: info: No code Apr 15 21:20:56 living-room volumio[3182]: info: CoreStateMachine::pushState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioPushState Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::volumioGetState Apr 15 21:20:56 living-room volumio[3182]: info: CorePlayQueue::getTrack 0 Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Apr 15 21:20:56 living-room volumio[3182]: info: MRS: Pushing multiroomSync output Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.607+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.608+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.608+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.608+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.611+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.611+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.612+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.612+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.613+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.613+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.613+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.614+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.614+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.614+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.614+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.614+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.615+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.615+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.615+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.615+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.620+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.622+08:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" state=STATUS_STOPPED positionMs=0 volume=6 Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.623+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:52465 @ 0x310aff0" id= title= Apr 15 21:20:56 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:56.623+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%55,192.168.1.107:52465 @ 0x2f0bd10" id= title= Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: RAAT: Requesting Headphone Status Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 15 21:20:56 living-room volumio[3182]: info: MCU Signalled Headphone Mode Disabled Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 15 21:20:56 living-room volumio[3182]: info: Reporting Headphone State: false Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:56 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:56 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:56 living-room volumio[3182]: info: TidalConnect service started! Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.193+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio[3182]: info: Checking if install.sh is present Apr 15 21:20:57 living-room volumio[3182]: info: Executing install.sh Apr 15 21:20:57 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 15 21:20:57 living-room sudo[12856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:20:57 living-room sudo[12856]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:57 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:57 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:57 living-room sudo[12854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 15 21:20:57 living-room sudo[12854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:57 living-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 15 21:20:57 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:20:57 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:20:57 living-room volumio[3182]: info: creating filters folder and copying demo filters Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.351+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:20:57 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:20:57 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:20:57 living-room volumio[3182]: info: copying demo flters Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.424+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:57 living-room volumio[3182]: info: TidalConnect service stoped! Apr 15 21:20:57 living-room sudo[12862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:20:57 living-room sudo[12862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/ Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.575+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/2XEQ15/ Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/PEQ/ Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/convfir/ Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/convfir/kef.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/convfir/test.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/EQ15/ Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/EQ15/.Bass-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/EQ15/.Classic-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/EQ15/.Voice-FusionDsp.json Apr 15 21:20:57 living-room volumio[3182]: info: ./presets/EQ15/.Rock-FusionDsp.json Apr 15 21:20:57 living-room sudo[12862]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.595+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.595+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.596+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.596+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.597+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.597+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.598+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.598+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.598+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.599+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.600+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.601+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.602+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.602+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.603+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room sudo[12892]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 15 21:20:57 living-room sudo[12892]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:57 living-room volumio[3182]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:57 living-room volumio[3182]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 21:20:57 living-room volumio[3182]: info: Installing/fusiondsp dependencies Apr 15 21:20:57 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:57.707+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:57 living-room sudo[12897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 21:20:57 living-room sudo[12897]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:20:57 living-room sudo[12897]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:58 living-room volumio[3182]: info: Hit:1 http://archive.debian.org/debian buster InRelease Apr 15 21:20:58 living-room volumio5-onboarding[3848]: time=2026-04-15T21:20:58.139+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:20:58 living-room volumio[3182]: info: RAAT: Requesting Headphone Status Apr 15 21:20:58 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 15 21:20:58 living-room volumio[3182]: info: MCU Signalled Headphone Mode Disabled Apr 15 21:20:58 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 15 21:20:58 living-room volumio[3182]: info: Reporting Headphone State: false Apr 15 21:20:58 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 21:20:58 living-room volumio[3182]: info: Updating RAAT Signal Path Apr 15 21:20:59 living-room volumio[3182]: info: TidalConnect service started! Apr 15 21:20:59 living-room mpd[12893]: Apr 15 21:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 21:20:59 living-room systemd[1]: Started Music Player Daemon. Apr 15 21:20:59 living-room sudo[12492]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12696]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12335]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12576]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12164]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12722]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12856]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12808]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12227]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room sudo[12365]: pam_unix(sudo:session): session closed for user root Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: MPD error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: The expression evaluated to a falsy value: Apr 15 21:20:59 living-room volumio[3182]: assert.ok(self.idling) Apr 15 21:20:59 living-room volumio[3182]: error: updateQueue error: null Apr 15 21:21:00 living-room volumio[3182]: info: TidalConnect service started! Apr 15 21:21:03 living-room volumio[3182]: info: Reading package lists... Apr 15 21:21:03 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:03.388+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:04 living-room volumio[3182]: info: Building dependency tree... Apr 15 21:21:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:04.197+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:04 living-room volumio[3182]: info: Reading state information... Apr 15 21:21:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:04.208+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:04 living-room volumio[3182]: info: All packages are up to date. Apr 15 21:21:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:04.282+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:04 living-room sudo[12892]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:04 living-room sudo[13037]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 15 21:21:04 living-room sudo[13037]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:04 living-room volumio[3182]: info: Reading package lists... Apr 15 21:21:04 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:04.485+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:05 living-room volumio[3182]: info: Building dependency tree... Apr 15 21:21:05 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:05.181+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:05 living-room volumio[3182]: info: Reading state information... Apr 15 21:21:05 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:05.192+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:06 living-room volumio[3182]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Apr 15 21:21:06 living-room sudo[13037]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:06 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:06.575+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:06 living-room volumio[3182]: info: python3-pip is already the newest version (18.1-5). Apr 15 21:21:06 living-room volumio[3182]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Apr 15 21:21:06 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:06.584+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:06 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:06.585+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:06 living-room sudo[13041]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Apr 15 21:21:06 living-room sudo[13041]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:06 living-room volumio[3182]: info: lib/systemd/system/fusiondsp.service Apr 15 21:21:06 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:06.609+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:06 living-room sudo[13041]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:08 living-room volumio[3182]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.418+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio[3182]: info: more info at http://www.winimage.com/zLibDll/unzip.html Apr 15 21:21:08 living-room volumio[3182]: info: Apr 15 21:21:08 living-room volumio[3182]: info: cgui-1.0.0.zip opened Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/LICENSE.txt Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/README.md Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/build/ Apr 15 21:21:08 living-room sudo[13046]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/robots.txt Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/logo512.png Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/asset-manifest.json Apr 15 21:21:08 living-room sudo[13046]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/index.html Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/.put_statics_here Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/favicon.ico Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/logo192.png Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/manifest.json Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/css-variables.css Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/service-worker.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/build/static/ Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/build/static/css/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Apr 15 21:21:08 living-room sudo[13046]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/main.1503f6cd.css Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/build/static/media/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/build/static/js/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.71e11955.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Apr 15 21:21:08 living-room sudo[13048]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.71e11955.js.map Apr 15 21:21:08 living-room sudo[13048]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/config/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/config/camillagui.yml Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/config/gui-config.yml Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/backend/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/filters_test.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/version.py Apr 15 21:21:08 living-room sudo[13048]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/filters.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/settings.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/views.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/filterdefaults.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__init__.py Apr 15 21:21:08 living-room volumio[3182]: info: creating directory: cgui/backend/__pycache__/ Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/routes.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/backend/filemanagement.py Apr 15 21:21:08 living-room volumio[3182]: info: extracting: cgui/main.py Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.509+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.510+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.511+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.511+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.512+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.512+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.513+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.513+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.514+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.514+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.515+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room sudo[13050]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.515+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.516+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room sudo[13050]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.517+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.518+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.520+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.521+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.522+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.523+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.523+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.532+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.533+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.534+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.536+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.537+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.538+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.539+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room sudo[13050]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.540+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.542+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.543+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.544+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.545+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.546+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.547+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.548+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.549+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.549+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.550+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.551+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.552+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.553+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.554+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.555+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.556+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.556+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.557+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.558+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.560+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.561+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.563+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.565+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:08 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:08.566+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:14 living-room volumio[3182]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Apr 15 21:21:14 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:14.683+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:14 living-room volumio[3182]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-uvk7u6k5 Apr 15 21:21:14 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:14.697+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:17 living-room volumio[3182]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Apr 15 21:21:17 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:17.594+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:17 living-room volumio[3182]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Apr 15 21:21:17 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:17.615+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:17 living-room volumio[3182]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Apr 15 21:21:17 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:17.630+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:17 living-room volumio[3182]: info: Building wheels for collected packages: camilladsp Apr 15 21:21:17 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:17.670+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:17 living-room volumio[3182]: info: Running setup.py bdist_wheel for camilladsp: started Apr 15 21:21:17 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:17.685+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:18 living-room go-librespot[3529]: time="2026-04-15T21:21:18+08:00" level=trace msg="received accesspoint ping" Apr 15 21:21:18 living-room go-librespot[3529]: time="2026-04-15T21:21:18+08:00" level=trace msg="received accesspoint pong ack" Apr 15 21:21:18 living-room go-librespot[3529]: time="2026-04-15T21:21:18+08:00" level=trace msg="sent dealer ping" Apr 15 21:21:18 living-room go-librespot[3529]: time="2026-04-15T21:21:18+08:00" level=trace msg="received dealer pong" Apr 15 21:21:19 living-room volumio[3182]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Apr 15 21:21:19 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:19.187+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:19 living-room volumio[3182]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-17fsxprl/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Apr 15 21:21:19 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:19.205+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:19 living-room volumio[3182]: info: Successfully built camilladsp Apr 15 21:21:19 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:19.228+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:26 living-room volumio[3182]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Apr 15 21:21:26 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:26.276+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:26 living-room volumio[3182]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-hjdzmjx6 Apr 15 21:21:26 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:26.295+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:28 living-room volumio[3182]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Apr 15 21:21:28 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:28.948+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:28 living-room volumio[3182]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Apr 15 21:21:28 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:28.976+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:28 living-room volumio[3182]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Apr 15 21:21:28 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:28.992+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.167+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.192+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.208+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.339+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.502+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.549+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.574+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Building wheels for collected packages: camilladsp-plot Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.697+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:29 living-room volumio[3182]: info: Running setup.py bdist_wheel for camilladsp-plot: started Apr 15 21:21:29 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:29.713+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:31 living-room volumio[3182]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Apr 15 21:21:31 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:31.288+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:31 living-room volumio[3182]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-rbbono6c/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Apr 15 21:21:31 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:31.304+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:31 living-room volumio[3182]: info: Successfully built camilladsp-plot Apr 15 21:21:31 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:31.343+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:31 living-room volumio[3182]: info: copying hw detection script Apr 15 21:21:31 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:31.909+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:31 living-room volumio[3182]: info: Detected cpu architecture as aarch64 Apr 15 21:21:31 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:31.941+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:33 living-room sudo[13170]: 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 Apr 15 21:21:33 living-room sudo[13170]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:33 living-room sudo[13170]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:33 living-room sudo[13172]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Apr 15 21:21:33 living-room sudo[13172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:33 living-room sudo[13172]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:33 living-room sudo[13174]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Apr 15 21:21:33 living-room sudo[13174]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:33 living-room volumio[3182]: info: Reading package lists... Apr 15 21:21:33 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:33.545+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:34 living-room volumio[3182]: info: Building dependency tree... Apr 15 21:21:34 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:34.245+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:34 living-room volumio[3182]: info: Reading state information... Apr 15 21:21:34 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:34.264+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:35 living-room volumio[3182]: info: drc is already the newest version (3.2.2~dfsg0-2). Apr 15 21:21:35 living-room sudo[13174]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:35 living-room sudo[12854]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:35 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:35.653+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:35 living-room volumio[3182]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Apr 15 21:21:35 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:35.660+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:35 living-room volumio[3182]: info: Install script completed Apr 15 21:21:35 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:35.685+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:35 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:35.692+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:35 living-room volumio[3182]: info: Done installing plugin. Apr 15 21:21:35 living-room volumio[3182]: info: Enabling plugin fusiondsp Apr 15 21:21:35 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 21:21:35 living-room volumio[3182]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] Apr 15 21:21:35 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 21:21:35 living-room volumio[3182]: info: Folder /tmp/plugins removed Apr 15 21:21:35 living-room volumio[3182]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 21:21:35 living-room volumio[3182]: Plugin install end detected on script Apr 15 21:21:35 living-room volumio[3182]: info: Folder /data/temp removed Apr 15 21:21:35 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:21:35 living-room volumio[3182]: info: FusionDsp - Apr 15 21:21:35 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:21:36 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:21:36 living-room volumio[3182]: info: FusionDsp - Apr 15 21:21:36 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:21:46 living-room volumio[3182]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/primo2rev2/buster/armhf Apr 15 21:21:46 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:46.073+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:47 living-room volumio[3182]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/primo2rev2/buster/armhf Apr 15 21:21:47 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 15 21:21:47 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:47.991+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:47 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:47.996+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room sudo[13199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 21:21:48 living-room sudo[13199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:48 living-room systemd[1]: Stopping Music Player Daemon... Apr 15 21:21:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:21:48 living-room systemd[1]: mpd.service: Succeeded. Apr 15 21:21:48 living-room systemd[1]: Stopped Music Player Daemon. Apr 15 21:21:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 21:21:48 living-room systemd[1]: Starting Music Player Daemon... Apr 15 21:21:48 living-room volumio[3182]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.180+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio[3182]: info: Check plugin dependencies Apr 15 21:21:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.236+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio[3182]: info: Rename folder Apr 15 21:21:48 living-room sudo[13203]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 21:21:48 living-room sudo[13203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:48 living-room sudo[13203]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:48 living-room volumio[3182]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.287+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio[3182]: info: Move to category Apr 15 21:21:48 living-room go-librespot[3529]: time="2026-04-15T21:21:48+08:00" level=trace msg="sent dealer ping" Apr 15 21:21:48 living-room go-librespot[3529]: time="2026-04-15T21:21:48+08:00" level=trace msg="received dealer pong" Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.581+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio[3182]: info: Checking if install.sh is present Apr 15 21:21:48 living-room volumio[3182]: info: Executing install.sh Apr 15 21:21:48 living-room sudo[13223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/install.sh Apr 15 21:21:48 living-room sudo[13223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 21:21:48 living-room sudo[13223]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:48 living-room volumio[3182]: info: Installing mpd http output Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.731+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio[3182]: Plugin install end detected on script Apr 15 21:21:48 living-room volumio[3182]: info: Install script completed Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.741+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:48.745+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:48 living-room volumio[3182]: info: Done installing plugin. Apr 15 21:21:48 living-room volumio[3182]: info: Enabling plugin mpdoutput Apr 15 21:21:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 21:21:48 living-room volumio[3182]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] Apr 15 21:21:48 living-room volumio[3182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 21:21:48 living-room volumio[3182]: info: Folder /tmp/plugins removed Apr 15 21:21:48 living-room volumio[3182]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 21:21:48 living-room volumio[3182]: info: Folder /data/temp removed Apr 15 21:21:48 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:21:48 living-room volumio[3182]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 21:21:48 living-room volumio[3182]: info: FusionDsp - Apr 15 21:21:49 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:21:49 living-room volumio[3182]: info: FusionDsp - Apr 15 21:21:49 living-room volumio[3182]: info: FusionDsp - undefined Apr 15 21:21:50 living-room mpd[13216]: Apr 15 21:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 21:21:50 living-room systemd[1]: Started Music Player Daemon. Apr 15 21:21:50 living-room sudo[13199]: pam_unix(sudo:session): session closed for user root Apr 15 21:21:50 living-room volumio[3182]: error: updateQueue error: null Apr 15 21:21:52 living-room volumio[3182]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Apr 15 21:21:52 living-room volumio5-onboarding[3848]: time=2026-04-15T21:21:52.959+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 15 21:21:55 living-room volumio[3182]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Apr 15 21:21:55 living-room volumio[3182]: info: FusionDsp - Stopping FusionDsp service Apr 15 21:21:55 living-room volumio[3182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 21:21:55 living-room volumio[3182]: TypeError: Cannot read property 'stop' of null Apr 15 21:21:55 living-room volumio[3182]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Apr 15 21:21:55 living-room volumio[3182]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 15 21:21:55 living-room volumio[3182]: at /volumio/app/pluginmanager.js:956:12 Apr 15 21:21:55 living-room volumio[3182]: at ChildProcess.exithandler (child_process.js:374:7) Apr 15 21:21:55 living-room volumio[3182]: at ChildProcess.emit (events.js:400:28) Apr 15 21:21:55 living-room volumio[3182]: at maybeClose (internal/child_process.js:1088:16) Apr 15 21:21:55 living-room volumio[3182]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Apr 15 21:21:55 living-room volumio[3182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 21:21:56 living-room sudo[13311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-15 21:20 Apr 15 21:21:56 living-room sudo[13311]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 12:16:07 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="88686576587e39571d6cf25f3920fbc0"