Mar 27 09:29:15 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 09:29:15 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 09:29:18 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 09:29:34 volumio volumio[1413]: info: Enabling plugin fusiondsp Mar 27 09:29:34 volumio volumio[1413]: info: Loading plugin "fusiondsp"... Mar 27 09:29:34 volumio volumio[1413]: info: Applying required configuration parameters for plugin fusiondsp Mar 27 09:29:34 volumio volumio[1413]: info: Preparing to generate the ALSA configuration file Mar 27 09:29:34 volumio volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 27 09:29:34 volumio volumio[1413]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 27 09:29:34 volumio volumio[1413]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 27 09:29:34 volumio volumio[1413]: info: Reading ALSA contributions from plugins. Mar 27 09:29:34 volumio volumio[1413]: info: Asound.conf file written Mar 27 09:29:34 volumio sudo[10838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 27 09:29:34 volumio sudo[10838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10838]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Mar 27 09:29:34 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Mar 27 09:29:34 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: info: Output device has changed, restarting MPD Mar 27 09:29:34 volumio volumio[1413]: info: Output device has changed, restarting Shairport Sync Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:34 volumio sudo[10846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 09:29:34 volumio sudo[10846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10846]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio sudo[10848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 09:29:34 volumio sudo[10848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 09:29:34 volumio volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: setDeactiveState invoked Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:34 volumio vtcs[2385]: [2026-03-27 09:29:34.401] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 27 09:29:34 volumio vtcs[2385]: [2026-03-27 09:29:34.401] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 27 09:29:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 09:29:34 volumio volumio[1413]: info: PLUGIN START: fusiondsp Mar 27 09:29:34 volumio sudo[10858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:34 volumio sudo[10858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio volumio[1413]: info: FusionDsp - CamillaDSP binary found. Mar 27 09:29:34 volumio volumio[1413]: info: Loading i18n strings for locale en Mar 27 09:29:34 volumio volumio[1413]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Mar 27 09:29:34 volumio volumio[1413]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Mar 27 09:29:34 volumio volumio[1413]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Mar 27 09:29:34 volumio volumio[1413]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: FusionDsp - mixtype--------------------- Hardware Mar 27 09:29:34 volumio volumio[1413]: info: Preparing to generate the ALSA configuration file Mar 27 09:29:34 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 27 09:29:34 volumio systemd[1]: vtcs.service: Killing process 2409 (vtcs) with signal SIGKILL. Mar 27 09:29:34 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 27 09:29:34 volumio systemd[1]: vtcs.service: Consumed 1.211s CPU time. Mar 27 09:29:34 volumio volumio[1413]: info: Done. Mar 27 09:29:34 volumio sudo[10858]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 09:29:34 volumio systemd[1]: mpd.service: Consumed 36.507s CPU time. Mar 27 09:29:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 09:29:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 09:29:34 volumio sudo[10861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:34 volumio sudo[10861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 27 09:29:34 volumio volumio[1413]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 27 09:29:34 volumio volumio[1413]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 27 09:29:34 volumio volumio[1413]: info: Reading ALSA contributions from plugins. Mar 27 09:29:34 volumio volumio[1413]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 27 09:29:34 volumio volumio[1413]: info: MPD Permissions set Mar 27 09:29:34 volumio sudo[10870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 27 09:29:34 volumio sudo[10870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio volumio[1413]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 27 09:29:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 09:29:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio sudo[10870]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio sudo[10861]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio sudo[10876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 27 09:29:34 volumio sudo[10876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10873]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 09:29:34 volumio sudo[10873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 09:29:34 volumio sudo[10873]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 27 09:29:34 volumio qobuz-connect[2306]: 20260327 09:29:34.627 [2306.2306] INFO SampleApp: Stopping Local configuration server Mar 27 09:29:34 volumio volumio[1413]: info: Starting Shairport Sync Mar 27 09:29:34 volumio volumio[1413]: info: Asound.conf file unchanged, so no further update is needed Mar 27 09:29:34 volumio volumio[1413]: info: Output device has changed, restarting MPD Mar 27 09:29:34 volumio sudo[10885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 09:29:34 volumio sudo[10885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio volumio[1413]: info: Output device has changed, restarting Shairport Sync Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:34 volumio sudo[10889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 09:29:34 volumio sudo[10889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10889]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio sudo[10890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 09:29:34 volumio sudo[10890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 09:29:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:34 volumio systemd[1]: shairport-sync.service: Consumed 8.189s CPU time. Mar 27 09:29:34 volumio volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 09:29:34 volumio volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: setDeactiveState invoked Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:34 volumio volumio[1413]: info: Preparing to generate the ALSA configuration file Mar 27 09:29:34 volumio sudo[10885]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 09:29:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 27 09:29:34 volumio sudo[10903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:34 volumio sudo[10903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 09:29:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 09:29:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 09:29:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 09:29:34 volumio qobuz-connect[2306]: 20260327 09:29:34.752 [2306.2306] INFO SampleApp: shat down connection on UNIX socket Mar 27 09:29:34 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 27 09:29:34 volumio sudo[10924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:34 volumio sudo[10924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 27 09:29:34 volumio sudo[10876]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 27 09:29:34 volumio volumio[1413]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 27 09:29:34 volumio volumio[1413]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 27 09:29:34 volumio volumio[1413]: info: Reading ALSA contributions from plugins. Mar 27 09:29:34 volumio sudo[10930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 27 09:29:34 volumio sudo[10930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10903]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio volumio[1413]: info: MPD Permissions set Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: setDeactiveState invoked Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:34 volumio volumio[1413]: info: Shairport-Sync Started Mar 27 09:29:34 volumio sudo[10924]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio sudo[10920]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 09:29:34 volumio sudo[10920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 09:29:34 volumio volumio[1413]: info: Executing endpoint qc_getconfig Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 27 09:29:34 volumio sudo[10920]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.847 [10927.10927] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.849 [10927.10927] INFO VolumeManager: [0x11ba2c8]: Setting new playback volume: 75 Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.849 [10927.10927] INFO VolumeManager: [0x11ba2c8]: Setting new mute state: 0 Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.849 [10927.10927] INFO AudioStreamManager: [0x11ba020]: Setting new audio download buffer size: 1048576 Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.849 [10927.10927] INFO QobuzConnect: [0x11bab90]: Client initialized! Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.849 [10927.10927] INFO SampleApp: Starting Avahi advertising, name: volumio, service name: _qobuz-connect._tcp Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.861 [10927.10927] INFO LocalConfigManager: [0x11b9d48]: Starting Local Configuration server Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.861 [10927.10927] INFO SampleApp: Starting Local configuration server Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.861 [10927.10927] INFO SampleApp: Connected to UNIX socket client 0x11a4818 Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: Starting Shairport Sync Mar 27 09:29:34 volumio sudo[10930]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio volumio[1413]: info: Asound.conf file written Mar 27 09:29:34 volumio sudo[10950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 27 09:29:34 volumio sudo[10950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 09:29:34 volumio sudo[10949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 27 09:29:34 volumio sudo[10955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 27 09:29:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 09:29:34 volumio sudo[10955]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio qobuz-connect[10927]: 20260327 09:29:34.915 [10927.10927] INFO SampleApp: Stopping Local configuration server Mar 27 09:29:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 09:29:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Mar 27 09:29:34 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Mar 27 09:29:34 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Mar 27 09:29:34 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 27 09:29:34 volumio volumio[1413]: info: Output device has changed, restarting MPD Mar 27 09:29:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:34 volumio volumio[1413]: info: Output device has changed, restarting Shairport Sync Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:34 volumio sudo[10949]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio sudo[10962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 09:29:34 volumio sudo[10962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio sudo[10962]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:34 volumio volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 09:29:34 volumio volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:34 volumio sudo[10965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 09:29:34 volumio sudo[10965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:34 volumio volumio[1413]: info: QobuzConnect: setDeactiveState invoked Mar 27 09:29:34 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:34 volumio volumio[1413]: info: Preparing to generate the ALSA configuration file Mar 27 09:29:34 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 27 09:29:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 09:29:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 27 09:29:35 volumio sudo[10988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:35 volumio sudo[10988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 09:29:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 09:29:35 volumio sudo[10992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:35 volumio sudo[10992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 27 09:29:35 volumio volumio[1413]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 27 09:29:35 volumio volumio[1413]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 27 09:29:35 volumio volumio[1413]: info: Reading ALSA contributions from plugins. Mar 27 09:29:35 volumio volumio[1413]: info: MPD Permissions set Mar 27 09:29:35 volumio volumio[1413]: info: Shairport-Sync Started Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 09:29:35 volumio sudo[11000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 27 09:29:35 volumio sudo[11000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: Starting Shairport Sync Mar 27 09:29:35 volumio sudo[11000]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[10992]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[10988]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[11009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 27 09:29:35 volumio sudo[11002]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 09:29:35 volumio sudo[11002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 09:29:35 volumio sudo[11010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 09:29:35 volumio volumio[1413]: info: Asound.conf file written Mar 27 09:29:35 volumio sudo[11010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio sudo[11009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio sudo[11002]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[11018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 27 09:29:35 volumio sudo[11018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio sudo[11018]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 27 09:29:35 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 27 09:29:35 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 27 09:29:35 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Mar 27 09:29:35 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Mar 27 09:29:35 volumio volumio[1413]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Mar 27 09:29:35 volumio volumio[1413]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 27 09:29:35 volumio volumio[1413]: info: Output device has changed, restarting MPD Mar 27 09:29:35 volumio sudo[11026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 09:29:35 volumio sudo[11026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio volumio[1413]: info: Output device has changed, restarting Shairport Sync Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 09:29:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 09:29:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:35 volumio sudo[11026]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[11029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 09:29:35 volumio sudo[11029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:35 volumio sudo[11010]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 09:29:35 volumio volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 27 09:29:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 09:29:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 27 09:29:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 09:29:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 09:29:35 volumio volumio[1413]: info: QobuzConnect: setDeactiveState invoked Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:35 volumio sudo[11054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:35 volumio sudo[11054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio sudo[11056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 09:29:35 volumio sudo[11056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio volumio[1413]: info: MPD Permissions set Mar 27 09:29:35 volumio volumio[1413]: info: Shairport-Sync Started Mar 27 09:29:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:35 volumio volumio[1413]: info: Starting Shairport Sync Mar 27 09:29:35 volumio sudo[11065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 27 09:29:35 volumio sudo[11065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio sudo[11074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 09:29:35 volumio sudo[11074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio sudo[11056]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[11054]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio sudo[11066]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 09:29:35 volumio sudo[11066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 09:29:35 volumio sudo[11066]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 09:29:35 volumio sudo[11065]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 09:29:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:35 volumio sudo[11079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 27 09:29:35 volumio sudo[11079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 09:29:35 volumio sudo[11074]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:35 volumio volumio[1413]: info: Shairport-Sync Started Mar 27 09:29:36 volumio mpd[11080]: 2026-03-27T09:29:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 09:29:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 09:29:36 volumio sudo[11029]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio sudo[10965]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio sudo[10890]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio sudo[10848]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio volumio[1413]: error: MPD error: The expression evaluated to a falsy value: Mar 27 09:29:36 volumio volumio[1413]: assert.ok(self.idling) Mar 27 09:29:36 volumio volumio[1413]: error: The expression evaluated to a falsy value: Mar 27 09:29:36 volumio volumio[1413]: assert.ok(self.idling) Mar 27 09:29:36 volumio volumio[1413]: error: MPD error: The expression evaluated to a falsy value: Mar 27 09:29:36 volumio volumio[1413]: assert.ok(self.idling) Mar 27 09:29:36 volumio volumio[1413]: error: The expression evaluated to a falsy value: Mar 27 09:29:36 volumio volumio[1413]: assert.ok(self.idling) Mar 27 09:29:36 volumio volumio[1413]: error: MPD error: The expression evaluated to a falsy value: Mar 27 09:29:36 volumio volumio[1413]: assert.ok(self.idling) Mar 27 09:29:36 volumio volumio[1413]: error: The expression evaluated to a falsy value: Mar 27 09:29:36 volumio volumio[1413]: assert.ok(self.idling) Mar 27 09:29:36 volumio volumio[1413]: error: updateQueue error: null Mar 27 09:29:36 volumio volumio[1413]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 27 09:29:36 volumio volumio[1413]: info: camilladsp service started and running in background, instance 1 Mar 27 09:29:36 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:29:36 volumio volumio[1413]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Mar 27 09:29:36 volumio volumio[1413]: info: FusionDsp loaded Mar 27 09:29:36 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 09:29:36 volumio volumio[1413]: info: FusionDsp - --[object Object] Mar 27 09:29:36 volumio volumio[1413]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 27 09:29:36 volumio volumio[1413]: info: Adding Signal Path Element [object Object] Mar 27 09:29:36 volumio volumio[1413]: info: Adding fusiondspeq DSP Signal Path Element Mar 27 09:29:36 volumio volumio[1413]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Mar 27 09:29:36 volumio sudo[11111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 27 09:29:36 volumio sudo[11111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:36 volumio volumio[1413]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 27 09:29:36 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Mar 27 09:29:36 volumio qobuz-connect[10927]: 20260327 09:29:36.873 [10927.10927] INFO SampleApp: shat down connection on UNIX socket Mar 27 09:29:36 volumio volumio[1413]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 27 09:29:36 volumio volumio[1413]: info: QobuzConnect: setDeactiveState invoked Mar 27 09:29:36 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:36 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 27 09:29:36 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 27 09:29:36 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 27 09:29:36 volumio sudo[10950]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio sudo[11009]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio sudo[11079]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:36 volumio volumio[1413]: info: Executing endpoint qc_getconfig Mar 27 09:29:36 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.936 [11116.11116] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 27 09:29:36 volumio volumio[1413]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 27 09:29:36 volumio volumio[1413]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.939 [11116.11116] INFO VolumeManager: [0x122d2c8]: Setting new playback volume: 75 Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.939 [11116.11116] INFO VolumeManager: [0x122d2c8]: Setting new mute state: 0 Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.939 [11116.11116] INFO AudioStreamManager: [0x122d020]: Setting new audio download buffer size: 1048576 Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.939 [11116.11116] INFO QobuzConnect: [0x122db90]: Client initialized! Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.939 [11116.11116] INFO SampleApp: Starting Avahi advertising, name: volumio, service name: _qobuz-connect._tcp Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.948 [11116.11116] INFO LocalConfigManager: [0x122cd48]: Starting Local Configuration server Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.948 [11116.11116] INFO SampleApp: Starting Local configuration server Mar 27 09:29:36 volumio qobuz-connect[11116]: 20260327 09:29:36.948 [11116.11116] INFO SampleApp: Connected to UNIX socket client 0x1217818 Mar 27 09:29:37 volumio qobuz-connect[11116]: 20260327 09:29:37.086 [11116.11116] INFO SampleApp: Playback volume changed: 75 Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:37 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:37 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:37 volumio volumio[1413]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:37 volumio volumio[1413]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:37 volumio sudo[11131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 27 09:29:37 volumio sudo[11131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:37 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 27 09:29:37 volumio sudo[11131]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:37 volumio volumio[1413]: info: Executing endpoint tc_getconfig Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 27 09:29:37 volumio vtcs[11134]: STARTING TidalConnect services, version: 1.6.1 Mar 27 09:29:37 volumio vtcs[11134]: STARTED TidalConnect services. Mar 27 09:29:37 volumio volumio[1413]: info: Executing endpoint tc_connect Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 27 09:29:37 volumio volumio[1413]: info: Connecting to TidalConnect Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Mar 27 09:29:37 volumio volumio[1413]: info: CoreStateMachine::pushState Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Mar 27 09:29:37 volumio volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Mar 27 09:29:37 volumio volumio[1413]: info: MRS: Pushing multiroomSync output Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:37 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Mar 27 09:29:37 volumio volumio[1413]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Mar 27 09:29:37 volumio volumio[1413]: info: CoreStateMachine::pushState Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Mar 27 09:29:37 volumio volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Mar 27 09:29:37 volumio volumio[1413]: info: MRS: Pushing multiroomSync output Mar 27 09:29:37 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Mar 27 09:29:37 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Mar 27 09:29:37 volumio volumio[1413]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Mar 27 09:29:37 volumio volumio[1413]: info: PeppySpectrum ---peppyspectrum status play Mar 27 09:29:37 volumio volumio[1413]: info: PeppySpectrum ---peppyspectrum status play Mar 27 09:29:37 volumio sudo[11148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 27 09:29:37 volumio sudo[11148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:37 volumio sudo[11152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 27 09:29:37 volumio sudo[11152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:37 volumio volumio[1413]: info: FusionDsp - Volumio is playing Mar 27 09:29:37 volumio volumio[1413]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 27 09:29:37 volumio volumio[1413]: info: FusionDsp - Volumio is playing Mar 27 09:29:37 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:29:37 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Mar 27 09:29:37 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:29:37 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Mar 27 09:29:37 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Mar 27 09:29:37 volumio sudo[11148]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:37 volumio sudo[11152]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:37 volumio volumio[1413]: info: peppyspectrum Daemon Started Mar 27 09:29:37 volumio volumio[1413]: info: peppyspectrum Daemon Started Mar 27 09:29:37 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:37 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:37 volumio volumio[1413]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:37 volumio volumio[1413]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:37 volumio sudo[11167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 27 09:29:37 volumio sudo[11167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:37 volumio sudo[11167]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:38 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Mar 27 09:29:38 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:38 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:38 volumio volumio[1413]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:38 volumio volumio[1413]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:38 volumio sudo[11179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 27 09:29:38 volumio sudo[11179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:38 volumio sudo[11179]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:38 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:38 volumio volumio[1413]: info: TidalConnect service stoped! Mar 27 09:29:38 volumio volumio[1413]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:38 volumio volumio[1413]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 27 09:29:38 volumio sudo[11191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 27 09:29:38 volumio sudo[11191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 09:29:38 volumio sudo[11191]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:39 volumio volumio[1413]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 09:29:39 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:29:39 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Mar 27 09:29:40 volumio volumio[1413]: info: TidalConnect service started! Mar 27 09:29:40 volumio volumio[1413]: info: TidalConnect service started! Mar 27 09:29:41 volumio volumio[1413]: info: TidalConnect service started! Mar 27 09:29:41 volumio volumio[1413]: info: TidalConnect service started! Mar 27 09:29:41 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Mar 27 09:29:41 volumio sudo[11111]: pam_unix(sudo:session): session closed for user root Mar 27 09:29:41 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:29:41 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms Mar 27 09:29:47 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:29:47 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 27 09:29:57 volumio volumio[1413]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 27 09:29:57 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:29:57 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 27 09:30:16 volumio volumio[1413]: info: CALLMETHOD: user_interface peppyspectrum updatelist Mar 27 09:30:16 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Mar 27 09:30:16 volumio volumio[1413]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Mar 27 09:30:17 volumio volumio[1413]: --2026-03-27 09:30:16-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Mar 27 09:30:17 volumio volumio[1413]: Resolving github.com (github.com)... 20.26.156.215 Mar 27 09:30:17 volumio volumio[1413]: Connecting to github.com (github.com)|20.26.156.215|:443... connected. Mar 27 09:30:17 volumio volumio[1413]: HTTP request sent, awaiting response... 302 Found Mar 27 09:30:17 volumio volumio[1413]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Mar 27 09:30:17 volumio volumio[1413]: --2026-03-27 09:30:17-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Mar 27 09:30:17 volumio volumio[1413]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.109.133, 185.199.108.133, ... Mar 27 09:30:17 volumio volumio[1413]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Mar 27 09:30:17 volumio volumio[1413]: HTTP request sent, awaiting response... 200 OK Mar 27 09:30:17 volumio volumio[1413]: Length: 471 [text/plain] Mar 27 09:30:17 volumio volumio[1413]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Mar 27 09:30:17 volumio volumio[1413]: 0K 100% 19.4M=0s Mar 27 09:30:17 volumio volumio[1413]: 2026-03-27 09:30:17 (19.4 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Mar 27 09:30:17 volumio volumio[1413]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 09:30:17 volumio volumio[1413]: info: CoreCommandRouter::Close All Modals sent Mar 27 09:30:17 volumio volumio[1413]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 27 09:30:17 volumio volumio[1413]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 27 09:30:17 volumio volumio[1413]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 27 09:30:18 volumio volumio[1413]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Mar 27 09:30:18 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Mar 27 09:30:22 volumio volumio[1413]: --2026-03-27 09:30:18-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Mar 27 09:30:22 volumio volumio[1413]: Resolving github.com (github.com)... 20.26.156.215 Mar 27 09:30:22 volumio volumio[1413]: Connecting to github.com (github.com)|20.26.156.215|:443... connected. Mar 27 09:30:22 volumio volumio[1413]: HTTP request sent, awaiting response... 404 Not Found Mar 27 09:30:22 volumio volumio[1413]: 2026-03-27 09:30:19 ERROR 404: Not Found. Mar 27 09:30:22 volumio volumio[1413]: --2026-03-27 09:30:19-- http://a/ Mar 27 09:30:22 volumio volumio[1413]: Resolving a (a)... failed: Device or resource busy. Mar 27 09:30:22 volumio volumio[1413]: wget: unable to resolve host address ‘a’ Mar 27 09:30:22 volumio volumio[1413]: --2026-03-27 09:30:19-- http://packet.zip/ Mar 27 09:30:22 volumio volumio[1413]: Resolving packet.zip (packet.zip)... 162.255.119.167 Mar 27 09:30:22 volumio volumio[1413]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Mar 27 09:30:22 volumio volumio[1413]: HTTP request sent, awaiting response... 302 Found Mar 27 09:30:22 volumio volumio[1413]: Location: http://www.packet.zip/ [following] Mar 27 09:30:22 volumio volumio[1413]: --2026-03-27 09:30:19-- http://www.packet.zip/ Mar 27 09:30:22 volumio volumio[1413]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Mar 27 09:30:22 volumio volumio[1413]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 27 09:30:22 volumio volumio[1413]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers. Mar 27 09:30:22 volumio volumio[1413]: Retrying. Mar 27 09:30:22 volumio volumio[1413]: --2026-03-27 09:30:20-- (try: 2) http://www.packet.zip/ Mar 27 09:30:22 volumio volumio[1413]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 27 09:30:22 volumio volumio[1413]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers. Mar 27 09:30:22 volumio volumio[1413]: Retrying. Mar 27 09:30:22 volumio volumio[1413]: --2026-03-27 09:30:22-- (try: 3) http://www.packet.zip/ Mar 27 09:30:22 volumio volumio[1413]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 27 09:30:22 volumio volumio[1413]: HTTP request sent, awaiting response... 403 Forbidden Mar 27 09:30:22 volumio volumio[1413]: 2026-03-27 09:30:22 ERROR 403: Forbidden. Mar 27 09:30:22 volumio volumio[1413]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Mar 27 09:30:22 volumio volumio[1413]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Mar 27 09:30:22 volumio volumio[1413]: /bin/rm: cannot remove 'a': No such file or directory Mar 27 09:30:22 volumio volumio[1413]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Mar 27 09:30:22 volumio volumio[1413]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 09:30:22 volumio volumio[1413]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Mar 27 09:30:22 volumio volumio[1413]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Mar 27 09:30:22 volumio volumio[1413]: /bin/rm: cannot remove 'a': No such file or directory Mar 27 09:30:22 volumio volumio[1413]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Mar 27 09:30:22 volumio volumio[1413]: at checkExecSyncError (node:child_process:887:11) Mar 27 09:30:22 volumio volumio[1413]: at execSync (node:child_process:959:15) Mar 27 09:30:22 volumio volumio[1413]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Mar 27 09:30:22 volumio volumio[1413]: at new Promise () Mar 27 09:30:22 volumio volumio[1413]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Mar 27 09:30:22 volumio volumio[1413]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Mar 27 09:30:22 volumio volumio[1413]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Mar 27 09:30:22 volumio volumio[1413]: at Socket.emit (node:events:514:28) Mar 27 09:30:22 volumio volumio[1413]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 27 09:30:22 volumio volumio[1413]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Mar 27 09:30:22 volumio volumio[1413]: status: 1, Mar 27 09:30:22 volumio volumio[1413]: signal: null, Mar 27 09:30:22 volumio volumio[1413]: output: [ Mar 27 09:30:22 volumio volumio[1413]: null, Mar 27 09:30:22 volumio volumio[1413]: , Mar 27 09:30:22 volumio volumio[1413]: Mar 27 09:30:22 volumio volumio[1413]: ], Mar 27 09:30:22 volumio volumio[1413]: pid: 11278, Mar 27 09:30:22 volumio volumio[1413]: stdout: , Mar 27 09:30:22 volumio volumio[1413]: stderr: Mar 27 09:30:22 volumio volumio[1413]: } Mar 27 09:30:22 volumio volumio[1413]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 09:30:22 volumio sudo[11294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 09:29' Mar 27 09:30:22 volumio sudo[11294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"