Dec 12 16:42:23 volumio-hq volumio[1315]: info: Tunnel connection is inactive, restarting it Dec 12 16:42:23 volumio-hq volumio[1315]: info: Starting Tunnel 1 Dec 12 16:42:23 volumio-hq volumio[1315]: info: Starting Tunnel Connection Checker Dec 12 16:42:25 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 16:42:27 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:42:27 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:42:27 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:42:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:42:34 volumio-hq volumio[1315]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:34 volumio-hq volumio[1315]: info: Restoring Previous Volume level: 100 false false Dec 12 16:42:34 volumio-hq volumio[1315]: info: Output device has changed, restarting MPD Dec 12 16:42:34 volumio-hq volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:34 volumio-hq sudo[5976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 16:42:34 volumio-hq sudo[5976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq sudo[5976]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq sudo[5978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 16:42:34 volumio-hq sudo[5978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 16:42:34 volumio-hq volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:34 volumio-hq vtcs[1952]: [2025-12-12 16:42:34.567] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 12 16:42:34 volumio-hq vtcs[1952]: [2025-12-12 16:42:34.568] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 12 16:42:34 volumio-hq volumio[1315]: info: Volume configurations have been set Dec 12 16:42:34 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:34 volumio-hq systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 16:42:34 volumio-hq sudo[5988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:34 volumio-hq sudo[5988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq sudo[5990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:34 volumio-hq sudo[5990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 12 16:42:34 volumio-hq volumio[1315]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 12 16:42:34 volumio-hq volumio[1315]: info: Disabling external Volume Control Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:42:34 volumio-hq systemd[1]: mpd.service: Deactivated successfully. Dec 12 16:42:34 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:42:34 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:34 volumio-hq systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 16:42:34 volumio-hq systemd[1]: mpd.service: Consumed 9.006s CPU time. Dec 12 16:42:34 volumio-hq systemd[1]: mpd.socket: Deactivated successfully. Dec 12 16:42:34 volumio-hq systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 16:42:34 volumio-hq systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 16:42:34 volumio-hq sudo[5999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:34 volumio-hq sudo[5999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq sudo[6002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:34 volumio-hq sudo[6002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 16:42:34 volumio-hq systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 16:42:34 volumio-hq sudo[6009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:42:34 volumio-hq sudo[6009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 12 16:42:34 volumio-hq sudo[6018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:42:34 volumio-hq sudo[6018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq volumio[1315]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 12 16:42:34 volumio-hq volumio[1315]: info: MPD Permissions set Dec 12 16:42:34 volumio-hq systemd[1]: vtcs.service: Deactivated successfully. Dec 12 16:42:34 volumio-hq systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:34 volumio-hq sudo[6015]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 16:42:34 volumio-hq sudo[6015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:34 volumio-hq sudo[6015]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 16:42:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 16:42:34 volumio-hq sudo[5990]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq volumio[1315]: info: Starting Shairport Sync Dec 12 16:42:34 volumio-hq sudo[6009]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq sudo[5988]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq volumio[1315]: info: Preparing to generate the ALSA configuration file Dec 12 16:42:34 volumio-hq sudo[6002]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq volumio[1315]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 12 16:42:34 volumio-hq volumio[1315]: info: Reading ALSA contributions from plugins. Dec 12 16:42:34 volumio-hq sudo[5999]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq sudo[6038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 16:42:34 volumio-hq sudo[6038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq sudo[6018]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq volumio[1315]: info: Asound.conf file written Dec 12 16:42:34 volumio-hq sudo[6048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:42:34 volumio-hq sudo[6048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq sudo[6039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:42:34 volumio-hq systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 16:42:34 volumio-hq systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 16:42:34 volumio-hq systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:42:34 volumio-hq systemd[1]: shairport-sync.service: Consumed 2.803s CPU time. Dec 12 16:42:34 volumio-hq sudo[6039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq sudo[6049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 12 16:42:34 volumio-hq sudo[6049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:34 volumio-hq systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:42:34 volumio-hq sudo[6038]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:34 volumio-hq systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 12 16:42:34 volumio-hq qobuz-connect[1858]: 20251212 16:42:34.859 [1858.1858] INFO SampleApp: Stopping Local configuration server Dec 12 16:42:35 volumio-hq mpd[6031]: 2025-12-12T16:42:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 16:42:35 volumio-hq systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 16:42:35 volumio-hq sudo[5978]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:36 volumio-hq qobuz-connect[1858]: 20251212 16:42:36.559 [1858.1858] INFO SampleApp: shat down connection on UNIX socket Dec 12 16:42:36 volumio-hq systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 12 16:42:36 volumio-hq systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 16:42:36 volumio-hq systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 16:42:36 volumio-hq sudo[6039]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:36 volumio-hq sudo[6048]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq sudo[6049]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi0 Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 12 16:42:38 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi0 Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi1 Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 12 16:42:38 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi1 Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card K11 Dec 12 16:42:38 volumio-hq volumio[1315]: Found hardware: "USB-Audio" "USB Mixer" "USB2972:0077" "" "" Dec 12 16:42:38 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card K11 Dec 12 16:42:38 volumio-hq volumio[1315]: info: Output device has changed, restarting MPD Dec 12 16:42:38 volumio-hq volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:38 volumio-hq sudo[6079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 16:42:38 volumio-hq sudo[6079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq sudo[6078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 16:42:38 volumio-hq sudo[6078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq sudo[6078]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 16:42:38 volumio-hq volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:38 volumio-hq systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 16:42:38 volumio-hq sudo[6090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:38 volumio-hq sudo[6090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq sudo[6092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:38 volumio-hq sudo[6092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq systemd[1]: mpd.service: Deactivated successfully. Dec 12 16:42:38 volumio-hq systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 16:42:38 volumio-hq systemd[1]: mpd.socket: Deactivated successfully. Dec 12 16:42:38 volumio-hq systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 16:42:38 volumio-hq systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 16:42:38 volumio-hq sudo[6100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:42:38 volumio-hq sudo[6100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPause Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreStateMachine::pause Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreStateMachine::stPlaybackTimer Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreStateMachine::servicePause Dec 12 16:42:38 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePause Dec 12 16:42:38 volumio-hq volumio[1315]: info: [1765528958784] ControllerTidal::pause Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 16:42:38 volumio-hq volumio[1315]: info: ControllerMpd::pause Dec 12 16:42:38 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand pause Dec 12 16:42:38 volumio-hq volumio[1315]: info: MPD Permissions set Dec 12 16:42:38 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:38 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:38 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:38 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:38 volumio-hq volumio[1315]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 12 16:42:38 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:38 volumio-hq systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 16:42:38 volumio-hq systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 16:42:38 volumio-hq volumio[1315]: info: Shairport-Sync Started Dec 12 16:42:38 volumio-hq volumio[1315]: info: Executing endpoint qc_getconfig Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.822 [6070.6070] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 12 16:42:38 volumio-hq volumio[1315]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.824 [6070.6070] INFO VolumeManager: [0x557db8]: Setting new playback volume: 75 Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.824 [6070.6070] INFO VolumeManager: [0x557db8]: Setting new mute state: 0 Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.825 [6070.6070] INFO QobuzConnect: [0x558788]: Client initialized! Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.825 [6070.6070] INFO SampleApp: Starting Avahi advertising, name: Volumio HQ, service name: _qobuz-connect._tcp Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.833 [6070.6070] INFO LocalConfigManager: [0x557698]: Starting Local Configuration server Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.833 [6070.6070] INFO SampleApp: Starting Local configuration server Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.833 [6070.6070] INFO SampleApp: Connected to UNIX socket client 0x542818 Dec 12 16:42:38 volumio-hq sudo[6100]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq volumio[1315]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 12 16:42:38 volumio-hq volumio[1315]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 12 16:42:38 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:38 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:38 volumio-hq sudo[6090]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq sudo[6125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:42:38 volumio-hq sudo[6125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:38 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:38 volumio-hq sudo[6092]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 12 16:42:38 volumio-hq volumio[1315]: info: Starting Shairport Sync Dec 12 16:42:38 volumio-hq qobuz-connect[6070]: 20251212 16:42:38.882 [6070.6070] INFO SampleApp: Stopping Local configuration server Dec 12 16:42:38 volumio-hq sudo[6111]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 16:42:38 volumio-hq sudo[6111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 16:42:38 volumio-hq sudo[6134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:42:38 volumio-hq sudo[6111]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq sudo[6134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq volumio[1315]: info: Preparing to generate the ALSA configuration file Dec 12 16:42:38 volumio-hq sudo[6138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 16:42:38 volumio-hq sudo[6138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq sudo[6140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:42:38 volumio-hq sudo[6140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq volumio[1315]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 12 16:42:38 volumio-hq volumio[1315]: info: Reading ALSA contributions from plugins. Dec 12 16:42:38 volumio-hq volumio[1315]: info: Asound.conf file written Dec 12 16:42:38 volumio-hq sudo[6147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 12 16:42:38 volumio-hq sudo[6147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq sudo[6147]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi0 Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 12 16:42:38 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi0 Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi1 Dec 12 16:42:38 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 16:42:38 volumio-hq volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 12 16:42:38 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card vc4hdmi1 Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card K11 Dec 12 16:42:38 volumio-hq volumio[1315]: Found hardware: "USB-Audio" "USB Mixer" "USB2972:0077" "" "" Dec 12 16:42:38 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:42:38 volumio-hq volumio[1315]: No state is present for card K11 Dec 12 16:42:38 volumio-hq volumio[1315]: info: Output device has changed, restarting MPD Dec 12 16:42:38 volumio-hq systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 16:42:38 volumio-hq systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 12 16:42:38 volumio-hq volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:38 volumio-hq sudo[6134]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 16:42:38 volumio-hq systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:42:38 volumio-hq systemd[1]: shairport-sync.service: Consumed 1.517s CPU time. Dec 12 16:42:38 volumio-hq sudo[6154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 16:42:38 volumio-hq systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:42:38 volumio-hq volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 16:42:38 volumio-hq volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:38 volumio-hq sudo[6154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:38 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:42:38 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:38 volumio-hq sudo[6140]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq sudo[6138]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq sudo[6154]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:38 volumio-hq sudo[6158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 16:42:38 volumio-hq sudo[6158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:39 volumio-hq sudo[6172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:39 volumio-hq sudo[6172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:39 volumio-hq systemd[1]: mpd.service: Deactivated successfully. Dec 12 16:42:39 volumio-hq systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 16:42:39 volumio-hq systemd[1]: mpd.socket: Deactivated successfully. Dec 12 16:42:39 volumio-hq systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 16:42:39 volumio-hq systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 16:42:39 volumio-hq sudo[6170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:42:39 volumio-hq sudo[6170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:39 volumio-hq sudo[6197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:42:39 volumio-hq sudo[6197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:39 volumio-hq volumio[1315]: error: MPD error: Error: read ECONNRESET Dec 12 16:42:39 volumio-hq volumio[1315]: info: MPD Permissions set Dec 12 16:42:39 volumio-hq volumio[1315]: info: Shairport-Sync Started Dec 12 16:42:39 volumio-hq volumio[1315]: info: VolumeController::SetAlsaVolume100 Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:42:39 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:42:39 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:39 volumio-hq systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:42:39 volumio-hq volumio[1315]: info: Starting Shairport Sync Dec 12 16:42:39 volumio-hq sudo[6197]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 12 16:42:39 volumio-hq systemd[1]: vtcs.service: Deactivated successfully. Dec 12 16:42:39 volumio-hq systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 12 16:42:39 volumio-hq sudo[6206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:42:39 volumio-hq sudo[6206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:39 volumio-hq sudo[6207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 16:42:39 volumio-hq sudo[6207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:39 volumio-hq sudo[6172]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq sudo[6170]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq sudo[6204]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 16:42:39 volumio-hq sudo[6204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 16:42:39 volumio-hq systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 16:42:39 volumio-hq sudo[6204]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 16:42:39 volumio-hq systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:42:39 volumio-hq systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:42:39 volumio-hq sudo[6207]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq volumio[1315]: info: Shairport-Sync Started Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioNext Dec 12 16:42:39 volumio-hq volumio[1315]: info: CoreStateMachine::next Dec 12 16:42:39 volumio-hq volumio[1315]: info: ControllerMpd::next Dec 12 16:42:39 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand next Dec 12 16:42:39 volumio-hq mpd[6213]: 2025-12-12T16:42:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 16:42:39 volumio-hq systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 16:42:39 volumio-hq sudo[6158]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq sudo[6079]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:39 volumio-hq volumio[1315]: error: updateQueue error: null Dec 12 16:42:39 volumio-hq volumio[1315]: error: updateQueue error: null Dec 12 16:42:40 volumio-hq volumio[1315]: verbose: New Socket.io Connection to 192.168.1.240:3000 from 192.168.1.233 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 12 16:42:40 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 16:42:40 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 16:42:40 volumio-hq volumio[1315]: info: Discovery: Getting this device information Dec 12 16:42:40 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:40 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 16:42:40 volumio-hq qobuz-connect[6070]: 20251212 16:42:40.840 [6070.6070] INFO SampleApp: shat down connection on UNIX socket Dec 12 16:42:40 volumio-hq volumio[1315]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 12 16:42:40 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:42:40 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:40 volumio-hq systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 12 16:42:40 volumio-hq systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 16:42:40 volumio-hq systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 16:42:40 volumio-hq sudo[6125]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:40 volumio-hq sudo[6206]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:40 volumio-hq volumio[1315]: info: Executing endpoint qc_getconfig Dec 12 16:42:40 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.897 [6235.6235] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 12 16:42:40 volumio-hq volumio[1315]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 12 16:42:40 volumio-hq volumio[1315]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.900 [6235.6235] INFO VolumeManager: [0x8cfdb8]: Setting new playback volume: 75 Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.900 [6235.6235] INFO VolumeManager: [0x8cfdb8]: Setting new mute state: 0 Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.900 [6235.6235] INFO QobuzConnect: [0x8d0788]: Client initialized! Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.900 [6235.6235] INFO SampleApp: Starting Avahi advertising, name: Volumio HQ, service name: _qobuz-connect._tcp Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.905 [6235.6235] INFO LocalConfigManager: [0x8cf698]: Starting Local Configuration server Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.905 [6235.6235] INFO SampleApp: Starting Local configuration server Dec 12 16:42:40 volumio-hq qobuz-connect[6235]: 20251212 16:42:40.905 [6235.6235] INFO SampleApp: Connected to UNIX socket client 0x8ba818 Dec 12 16:42:41 volumio-hq qobuz-connect[6235]: 20251212 16:42:41.047 [6235.6235] INFO SampleApp: Playback volume changed: 75 Dec 12 16:42:41 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:41 volumio-hq sudo[6258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 16:42:41 volumio-hq sudo[6258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:41 volumio-hq systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 16:42:41 volumio-hq systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 16:42:41 volumio-hq systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 16:42:41 volumio-hq systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 16:42:41 volumio-hq sudo[6258]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:41 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:41 volumio-hq mpd_monitor.sh[6260]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 16:42:41 volumio-hq volumio[1315]: info: Successfully started MPD Monitor Dec 12 16:42:41 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:41 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:41 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:41 volumio-hq sudo[6273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:42:41 volumio-hq sudo[6273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:41 volumio-hq systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 12 16:42:41 volumio-hq sudo[6273]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:41 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:42:42 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:42:42 volumio-hq volumio[1315]: info: Executing endpoint tc_getconfig Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 12 16:42:42 volumio-hq vtcs[6276]: STARTING TidalConnect services, version: 1.5.2.56 Dec 12 16:42:42 volumio-hq vtcs[6276]: STARTED TidalConnect services. Dec 12 16:42:42 volumio-hq volumio[1315]: info: Executing endpoint tc_connect Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 12 16:42:42 volumio-hq volumio[1315]: info: Connecting to TidalConnect Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:42:42 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:42:42 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:42 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:42:42 volumio-hq volumio[1315]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:42:42 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:42:42 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:42:42 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:42 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:42:42 volumio-hq volumio[1315]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 12 16:42:42 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:42 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:42:42 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:42 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:42:42 volumio-hq sudo[6301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:42:42 volumio-hq sudo[6301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:42 volumio-hq sudo[6301]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:42 volumio-hq sudo[6307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 16:42:42 volumio-hq sudo[6307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:42 volumio-hq sudo[6310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 16:42:42 volumio-hq sudo[6310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:42:42 volumio-hq systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 16:42:42 volumio-hq systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 16:42:42 volumio-hq systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 16:42:42 volumio-hq systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 16:42:42 volumio-hq mpd_monitor.sh[6312]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 16:42:42 volumio-hq sudo[6307]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:42 volumio-hq volumio[1315]: info: Successfully started MPD Monitor Dec 12 16:42:42 volumio-hq systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 16:42:42 volumio-hq systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 16:42:42 volumio-hq systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 16:42:42 volumio-hq systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 16:42:42 volumio-hq sudo[6310]: pam_unix(sudo:session): session closed for user root Dec 12 16:42:42 volumio-hq mpd_monitor.sh[6316]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 16:42:42 volumio-hq volumio[1315]: info: Successfully started MPD Monitor Dec 12 16:42:44 volumio-hq volumio[1315]: error: updateQueue error: null Dec 12 16:42:44 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:42:45 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 16:42:49 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 16:42:51 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:42:51 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:42:51 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:42:51 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:42:51 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:42:51 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:42:52 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 12 16:42:53 volumio-hq volumio[1315]: info: browseTIDALUri took 633 milliseconds Dec 12 16:42:53 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:42:53 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:42:54 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 12 16:42:54 volumio-hq volumio[1315]: info: browseTIDALUri took 181 milliseconds Dec 12 16:42:54 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:42:56 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 12 16:42:56 volumio-hq volumio[1315]: info: browseTIDALUri took 181 milliseconds Dec 12 16:42:56 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:42:57 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 12 16:42:58 volumio-hq volumio[1315]: info: browseTIDALUri took 759 milliseconds Dec 12 16:42:58 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:43:00 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::ClearQueue Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::stop Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::updateTrackBlock Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrackBlock Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::stPlaybackTimer Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::serviceStop Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::serviceStop Dec 12 16:43:00 volumio-hq volumio[1315]: info: [1765528980158] ControllerTidal::stop Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::stop Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::clearPlayQueue Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::saveQueue Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushQueue Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::addQueueItems Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::addQueueItems Dec 12 16:43:00 volumio-hq volumio[1315]: info: Preload queue cleared Dec 12 16:43:00 volumio-hq volumio[1315]: info: Adding Item to queue: tidal://new/playlists/recommended/0ae80812-f8d6-4fc4-90ea-b2df4ecc3861 Dec 12 16:43:00 volumio-hq volumio[1315]: info: Using cached record of: tidal://new/playlists/recommended/0ae80812-f8d6-4fc4-90ea-b2df4ecc3861 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushQueue Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::saveQueue Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::updateTrackBlock Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrackBlock Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPlay Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::play index 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::stop Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::play index undefined Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::startPlaybackTimer Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: [1765528980178] ControllerTidal::clearAddPlayTrack Dec 12 16:43:00 volumio-hq volumio[1315]: info: Getting stream with soundQuality HI_RES Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand stop took 27 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: getStreamUrl took 341 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand stop took 1 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces system playlist update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Ignoring MPD Status Update Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand clear took 1 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==" Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces system playlist update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Ignoring MPD Status Update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces system playlist update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Ignoring MPD Status Update Dec 12 16:43:00 volumio-hq volumio[1315]: error: updateQueue error: null Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces system playlist update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Ignoring MPD Status Update Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 2ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==" took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 2ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 2ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand play Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces system playlist update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Ignoring MPD Status Update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces system playlist update Dec 12 16:43:00 volumio-hq volumio[1315]: info: Ignoring MPD Status Update Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 3ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand play took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 2ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 1ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces state update: player Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::getState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces state update: player Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::getState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces state update: player Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::getState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces state update: player Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::getState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces state update: player Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::getState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:43:00 volumio-hq volumio[1315]: info: Dec 12 16:43:00 volumio-hq volumio[1315]: ---------------------------- MPD announces state update: player Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand status took 4 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::getState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand status Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand status took 3 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand status took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand status took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand status took 1 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand status took 3 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseState Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","trackType":"tidal"} Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: CURRENT POSITION 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState stateService play Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState currentStatus stop Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","trackType":"tidal"} Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: CURRENT POSITION 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState stateService play Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState currentStatus play Dec 12 16:43:00 volumio-hq volumio[1315]: info: Received an update from plugin. extracting info from payload Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","trackType":"tidal"} Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: CURRENT POSITION 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState stateService play Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState currentStatus play Dec 12 16:43:00 volumio-hq volumio[1315]: info: Received an update from plugin. extracting info from payload Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","trackType":"tidal"} Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: CURRENT POSITION 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState stateService play Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState currentStatus play Dec 12 16:43:00 volumio-hq volumio[1315]: info: Received an update from plugin. extracting info from payload Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","trackType":"tidal"} Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: CURRENT POSITION 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState stateService play Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState currentStatus play Dec 12 16:43:00 volumio-hq volumio[1315]: info: Received an update from plugin. extracting info from payload Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 31ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 78ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 78ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 77ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 76ms Dec 12 16:43:00 volumio-hq volumio[1315]: info: sendMpdCommand playlistinfo took 72 milliseconds Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: ControllerMpd::parseTrackInfo Dec 12 16:43:00 volumio-hq volumio[1315]: info: ControllerMpd::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDI3NGMxZTM2YzZjNzAwN2E5NjQyNDRiMGU5YzFlYi5tcDQ/0.flac?token=1765532434~OTY5YjkwNzU4NDZlODk5ZGFmZGVlY2I0M2M5MTExODQxYzExOTc2MA==","trackType":"tidal"} Dec 12 16:43:00 volumio-hq volumio[1315]: verbose: CURRENT POSITION 0 Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState stateService play Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::syncState currentStatus play Dec 12 16:43:00 volumio-hq volumio[1315]: info: Received an update from plugin. extracting info from payload Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:00 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:00 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:00 volumio-hq volumio[1315]: info: ------------------------------ 92ms Dec 12 16:43:05 volumio-hq volumio[1315]: verbose: New Socket.io Connection to 192.168.1.240 from 192.168.1.233 UA: Mozilla/5.0 (Linux; Android 16; 23116PN5BC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 16:43:05 volumio-hq volumio[1315]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 12 16:43:05 volumio-hq volumio[1315]: info: Received Get System Info Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 16:43:05 volumio-hq volumio[1315]: info: Discovery: Getting this device information Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 16:43:05 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:05 volumio-hq volumio[1315]: info: Listing playlists Dec 12 16:43:06 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 12 16:43:06 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 16:43:08 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:08 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:08 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 12 16:43:08 volumio-hq volumio[1315]: info: Received Get System Version Dec 12 16:43:08 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 16:43:14 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:16 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:16 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:16 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:16 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:16 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:16 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 12 16:43:18 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 16:43:22 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:23 volumio-hq systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 12 16:43:23 volumio-hq systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 12 16:43:23 volumio-hq systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:23 volumio-hq volumio[1315]: info: Retrieving Cloud Streaming UI Dec 12 16:43:23 volumio-hq volumio[1315]: info: Getting Tidal Cloud Configuration Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:23 volumio-hq volumio[1315]: info: Getting Qobuz Cloud Configuration Dec 12 16:43:23 volumio-hq volumio[1315]: info: Asking plugin for UI Config Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:23 volumio-hq volumio[1315]: info: Getting Spotify Cloud Configuration Dec 12 16:43:23 volumio-hq volumio[1315]: info: Asking plugin for UI Config Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:23 volumio-hq volumio[1315]: info: Saving Spotify Acccount Dec 12 16:43:23 volumio-hq volumio[1315]: info: Got it Dec 12 16:43:23 volumio-hq volumio[1315]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 12 16:43:23 volumio-hq volumio[1315]: info: Got Tidal Cloud Configuration Dec 12 16:43:23 volumio-hq volumio[1315]: info: Got it Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 16:43:23 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 16:43:24 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 12 16:43:24 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 12 16:43:24 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:24 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:24 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:24 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:24 volumio-hq volumio[1315]: info: Executing endpoint metavolumio Dec 12 16:43:24 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 16:43:26 volumio-hq volumio[1315]: info: Disabling MyMusic plugin multiroom Dec 12 16:43:26 volumio-hq volumio[1315]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Dec 12 16:43:26 volumio-hq volumio[1315]: info: Disabling plugin multiroom Dec 12 16:43:26 volumio-hq volumio[1315]: info: Preparing to generate the ALSA configuration file Dec 12 16:43:26 volumio-hq volumio[1315]: info: Asound.conf file written Dec 12 16:43:26 volumio-hq sudo[6546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 12 16:43:26 volumio-hq sudo[6546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6546]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 16:43:26 volumio-hq volumio[1315]: No state is present for card vc4hdmi0 Dec 12 16:43:26 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 16:43:26 volumio-hq volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 12 16:43:26 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:43:26 volumio-hq volumio[1315]: No state is present for card vc4hdmi0 Dec 12 16:43:26 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 16:43:26 volumio-hq volumio[1315]: No state is present for card vc4hdmi1 Dec 12 16:43:26 volumio-hq volumio[1315]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 16:43:26 volumio-hq volumio[1315]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 12 16:43:26 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:43:26 volumio-hq volumio[1315]: No state is present for card vc4hdmi1 Dec 12 16:43:26 volumio-hq volumio[1315]: No state is present for card K11 Dec 12 16:43:26 volumio-hq volumio[1315]: Found hardware: "USB-Audio" "USB Mixer" "USB2972:0077" "" "" Dec 12 16:43:26 volumio-hq volumio[1315]: Hardware is initialized using a generic method Dec 12 16:43:26 volumio-hq volumio[1315]: No state is present for card K11 Dec 12 16:43:26 volumio-hq volumio[1315]: info: Output device has changed, restarting MPD Dec 12 16:43:26 volumio-hq sudo[6554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 16:43:26 volumio-hq sudo[6554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6554]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 16:43:26 volumio-hq volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 16:43:26 volumio-hq volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 16:43:26 volumio-hq sudo[6557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:26 volumio-hq vtcs[6276]: [2025-12-12 16:43:26.524] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 12 16:43:26 volumio-hq vtcs[6276]: [2025-12-12 16:43:26.525] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 12 16:43:26 volumio-hq sudo[6566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:43:26 volumio-hq sudo[6566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 16:43:26 volumio-hq sudo[6569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:43:26 volumio-hq sudo[6569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 12 16:43:26 volumio-hq systemd[1]: vtcs.service: Killing process 6280 (vtcs) with signal SIGKILL. Dec 12 16:43:26 volumio-hq systemd[1]: vtcs.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 12 16:43:26 volumio-hq volumio[1315]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 12 16:43:26 volumio-hq volumio[1315]: info: MPD Permissions set Dec 12 16:43:26 volumio-hq systemd[1]: mpd.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 16:43:26 volumio-hq systemd[1]: mpd.service: Consumed 1.105s CPU time. Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:43:26 volumio-hq sudo[6576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq systemd[1]: mpd.socket: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 16:43:26 volumio-hq systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: Starting Shairport Sync Dec 12 16:43:26 volumio-hq volumio[1315]: info: Preparing to generate the ALSA configuration file Dec 12 16:43:26 volumio-hq systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 16:43:26 volumio-hq volumio[1315]: info: Asound.conf file unchanged, so no further update is needed Dec 12 16:43:26 volumio-hq volumio[1315]: info: Output device has changed, restarting MPD Dec 12 16:43:26 volumio-hq systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 16:43:26 volumio-hq sudo[6585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 16:43:26 volumio-hq sudo[6585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 16:43:26 volumio-hq sudo[6588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 16:43:26 volumio-hq sudo[6590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6566]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 16:43:26 volumio-hq volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6576]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 16:43:26 volumio-hq systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:43:26 volumio-hq systemd[1]: shairport-sync.service: Consumed 1.609s CPU time. Dec 12 16:43:26 volumio-hq sudo[6588]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:26 volumio-hq sudo[6600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:43:26 volumio-hq sudo[6600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6569]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:43:26 volumio-hq sudo[6605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:43:26 volumio-hq volumio[1315]: info: MPD Permissions set Dec 12 16:43:26 volumio-hq sudo[6614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:43:26 volumio-hq sudo[6616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: Starting Shairport Sync Dec 12 16:43:26 volumio-hq sudo[6627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 16:43:26 volumio-hq sudo[6627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:43:26 volumio-hq sudo[6585]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq systemd[1]: mpd.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 16:43:26 volumio-hq volumio[1315]: info: Shairport-Sync Started Dec 12 16:43:26 volumio-hq volumio[1315]: info: Preparing to generate the ALSA configuration file Dec 12 16:43:26 volumio-hq sudo[6616]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq systemd[1]: mpd.socket: Deactivated successfully. Dec 12 16:43:26 volumio-hq volumio[1315]: info: Asound.conf file unchanged, so no further update is needed Dec 12 16:43:26 volumio-hq volumio[1315]: info: Output device has changed, restarting MPD Dec 12 16:43:26 volumio-hq systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 16:43:26 volumio-hq systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 16:43:26 volumio-hq sudo[6643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:43:26 volumio-hq sudo[6643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: Output device has changed, restarting Shairport Sync Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 16:43:26 volumio-hq sudo[6646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6646]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 16:43:26 volumio-hq volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 16:43:26 volumio-hq sudo[6649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:26 volumio-hq systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 16:43:26 volumio-hq systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 16:43:26 volumio-hq systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 12 16:43:26 volumio-hq qobuz-connect[6235]: 20251212 16:43:26.790 [6235.6235] INFO SampleApp: Stopping Local configuration server Dec 12 16:43:26 volumio-hq systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 16:43:26 volumio-hq sudo[6659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:43:26 volumio-hq sudo[6659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:43:26 volumio-hq sudo[6662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 16:43:26 volumio-hq sudo[6662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: MPD Permissions set Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq sudo[6670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 16:43:26 volumio-hq volumio[1315]: info: Starting Shairport Sync Dec 12 16:43:26 volumio-hq sudo[6678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 16:43:26 volumio-hq sudo[6678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6614]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6605]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6660]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 16:43:26 volumio-hq sudo[6660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 16:43:26 volumio-hq sudo[6660]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6670]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 16:43:26 volumio-hq systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:43:26 volumio-hq sudo[6684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:26 volumio-hq sudo[6627]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq qobuz-connect[6235]: 20251212 16:43:26.942 [6235.6235] INFO SampleApp: shat down connection on UNIX socket Dec 12 16:43:26 volumio-hq systemd[1]: mpd.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 16:43:26 volumio-hq systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 12 16:43:26 volumio-hq volumio[1315]: info: Shairport-Sync Started Dec 12 16:43:26 volumio-hq volumio[1315]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 12 16:43:26 volumio-hq volumio[1315]: info: QobuzConnect: setDeactiveState invoked Dec 12 16:43:26 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:26 volumio-hq systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 16:43:26 volumio-hq sudo[6678]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq volumio[1315]: info: Shairport-Sync Started Dec 12 16:43:26 volumio-hq sudo[6659]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:26 volumio-hq sudo[6662]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq systemd[1]: mpd.socket: Deactivated successfully. Dec 12 16:43:27 volumio-hq systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 16:43:27 volumio-hq systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 16:43:27 volumio-hq systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 16:43:27 volumio-hq systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 16:43:27 volumio-hq systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 16:43:27 volumio-hq sudo[6643]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq sudo[6600]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq sudo[6684]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq volumio[1315]: info: Executing endpoint qc_getconfig Dec 12 16:43:27 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.071 [6705.6705] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 12 16:43:27 volumio-hq volumio[1315]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 12 16:43:27 volumio-hq volumio[1315]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.074 [6705.6705] INFO VolumeManager: [0x1912db8]: Setting new playback volume: 75 Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.074 [6705.6705] INFO VolumeManager: [0x1912db8]: Setting new mute state: 0 Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.074 [6705.6705] INFO QobuzConnect: [0x1913788]: Client initialized! Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.074 [6705.6705] INFO SampleApp: Starting Avahi advertising, name: Volumio HQ, service name: _qobuz-connect._tcp Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.079 [6705.6705] INFO LocalConfigManager: [0x1912698]: Starting Local Configuration server Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.080 [6705.6705] INFO SampleApp: Starting Local configuration server Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.080 [6705.6705] INFO SampleApp: Connected to UNIX socket client 0x18fd818 Dec 12 16:43:27 volumio-hq sudo[6704]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 16:43:27 volumio-hq sudo[6704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 16:43:27 volumio-hq sudo[6704]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq qobuz-connect[6705]: 20251212 16:43:27.221 [6705.6705] INFO SampleApp: Playback volume changed: 75 Dec 12 16:43:27 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:27 volumio-hq mpd[6712]: 2025-12-12T16:43:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 16:43:27 volumio-hq systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 16:43:27 volumio-hq sudo[6557]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq sudo[6649]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:27 volumio-hq sudo[6590]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:28 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 12 16:43:28 volumio-hq volumio[1315]: error: MPD error: The expression evaluated to a falsy value: Dec 12 16:43:28 volumio-hq volumio[1315]: assert.ok(self.idling) Dec 12 16:43:28 volumio-hq volumio[1315]: error: The expression evaluated to a falsy value: Dec 12 16:43:28 volumio-hq volumio[1315]: assert.ok(self.idling) Dec 12 16:43:28 volumio-hq volumio[1315]: error: MPD error: The expression evaluated to a falsy value: Dec 12 16:43:28 volumio-hq volumio[1315]: assert.ok(self.idling) Dec 12 16:43:28 volumio-hq volumio[1315]: error: The expression evaluated to a falsy value: Dec 12 16:43:28 volumio-hq volumio[1315]: assert.ok(self.idling) Dec 12 16:43:28 volumio-hq volumio[1315]: error: updateQueue error: null Dec 12 16:43:29 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:43:29 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:43:29 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:43:29 volumio-hq sudo[6726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:43:29 volumio-hq sudo[6726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:29 volumio-hq systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 12 16:43:29 volumio-hq sudo[6726]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:29 volumio-hq volumio[1315]: info: Disabling MyMusic plugin bluetooth Dec 12 16:43:29 volumio-hq volumio[1315]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Dec 12 16:43:29 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: [FUNC] onStop Dec 12 16:43:29 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 12 16:43:29 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 12 16:43:29 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Dec 12 16:43:29 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Dec 12 16:43:29 volumio-hq sudo[6732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Dec 12 16:43:29 volumio-hq sudo[6732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:29 volumio-hq systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Dec 12 16:43:29 volumio-hq volumiobt[6735]: INFO [BTSTART] Disconnecting all Bluetooth devices... Dec 12 16:43:29 volumio-hq bluetoothd[991]: Adv Monitor app :1.45 disconnected from D-Bus Dec 12 16:43:29 volumio-hq volumiobt[6740]: INFO [BTSTART] Unregistering Bluetooth agent... Dec 12 16:43:29 volumio-hq volumiobt[6741]: No agent is registered Dec 12 16:43:29 volumio-hq volumiobt[6741]: [NEW] Media /org/bluez/hci0 Dec 12 16:43:29 volumio-hq volumiobt[6741]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 12 16:43:29 volumio-hq volumiobt[6741]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 12 16:43:29 volumio-hq volumiobt[6741]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 12 16:43:29 volumio-hq bluetoothd[991]: Adv Monitor app :1.46 disconnected from D-Bus Dec 12 16:43:29 volumio-hq volumiobt[6742]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Dec 12 16:43:29 volumio-hq volumio[1315]: info: Executing endpoint tc_getconfig Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 12 16:43:29 volumio-hq vtcs[6729]: STARTING TidalConnect services, version: 1.5.2.56 Dec 12 16:43:29 volumio-hq vtcs[6729]: STARTED TidalConnect services. Dec 12 16:43:29 volumio-hq volumio[1315]: info: Executing endpoint tc_connect Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: Connecting to TidalConnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:29 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:29 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:29 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:29 volumio-hq volumio[1315]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::servicePushState Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:29 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:29 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:29 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:29 volumio-hq volumio[1315]: info: CorePlayQueue::getTrack 0 Dec 12 16:43:29 volumio-hq volumio[1315]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:43:29 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:43:29 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:43:29 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:43:29 volumio-hq volumio[1315]: info: TidalConnect service stoped! Dec 12 16:43:30 volumio-hq volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:43:30 volumio-hq volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 16:43:30 volumio-hq sudo[6779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:43:30 volumio-hq sudo[6779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:30 volumio-hq sudo[6767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 16:43:30 volumio-hq sudo[6767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:30 volumio-hq volumiobt[6781]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Dec 12 16:43:30 volumio-hq sudo[6779]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:30 volumio-hq sudo[6767]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:30 volumio-hq systemd[1]: volumiobt.service: Deactivated successfully. Dec 12 16:43:30 volumio-hq systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Dec 12 16:43:30 volumio-hq sudo[6732]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:31 volumio-hq volumio[1315]: info: Disabling MyMusic plugin upnp_browser Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 16:43:31 volumio-hq volumio[1315]: Cannot find translation for source TIDAL Dec 12 16:43:31 volumio-hq volumio[1315]: info: Disabling plugin upnp_browser Dec 12 16:43:31 volumio-hq volumio[1315]: info: Done. Dec 12 16:43:31 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Dec 12 16:43:31 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreStateMachine::resetVolumioState Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreStateMachine::getcurrentVolume Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreStateMachine::pushState Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioPushState Dec 12 16:43:31 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output update for this device Dec 12 16:43:31 volumio-hq volumio[1315]: info: MRS: Pushing multiroomSync output Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioGetState Dec 12 16:43:31 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioStop Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreStateMachine::stop Dec 12 16:43:31 volumio-hq volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 16:43:31 volumio-hq bluetoothd[991]: Path / reserved for Adv Monitor app :1.48 Dec 12 16:43:31 volumio-hq bluetoothd[991]: Adv Monitor app :1.48 disconnected from D-Bus Dec 12 16:43:31 volumio-hq volumio[1315]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered off Dec 12 16:43:31 volumio-hq volumio[1315]: info: Disabling plugin bluetooth Dec 12 16:43:31 volumio-hq sudo[6806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 16:43:31 volumio-hq sudo[6806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:31 volumio-hq sudo[6808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 16:43:31 volumio-hq sudo[6808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:31 volumio-hq sudo[6810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 16:43:31 volumio-hq sudo[6810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:31 volumio-hq systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 16:43:31 volumio-hq systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 16:43:31 volumio-hq systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 16:43:31 volumio-hq systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 16:43:31 volumio-hq sudo[6806]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:31 volumio-hq sudo[6808]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:31 volumio-hq sudo[6810]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:31 volumio-hq mpd_monitor.sh[6814]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 16:43:31 volumio-hq volumio[1315]: info: Successfully started MPD Monitor Dec 12 16:43:31 volumio-hq volumio[1315]: info: Successfully started MPD Monitor Dec 12 16:43:31 volumio-hq volumio[1315]: info: Successfully started MPD Monitor Dec 12 16:43:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 12 16:43:34 volumio-hq volumio[1315]: info: Disabling MyMusic plugin airplay_emulation Dec 12 16:43:34 volumio-hq volumio[1315]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Dec 12 16:43:34 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 16:43:34 volumio-hq volumio[1315]: Cannot find translation for source TIDAL Dec 12 16:43:34 volumio-hq volumio[1315]: info: Disabling plugin airplay_emulation Dec 12 16:43:34 volumio-hq volumio[1315]: info: Done. Dec 12 16:43:34 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:43:34 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:43:34 volumio-hq volumio[1315]: info: TidalConnect service started! Dec 12 16:43:34 volumio-hq sudo[6820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Dec 12 16:43:34 volumio-hq sudo[6820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:34 volumio-hq systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 16:43:34 volumio-hq systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 16:43:34 volumio-hq systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 16:43:34 volumio-hq systemd[1]: shairport-sync.service: Consumed 1.530s CPU time. Dec 12 16:43:34 volumio-hq sudo[6820]: pam_unix(sudo:session): session closed for user root Dec 12 16:43:34 volumio-hq volumio[1315]: Upnp client error: Error: This socket has been ended by the other party Dec 12 16:43:34 volumio-hq volumio[1315]: info: Shairport-Sync Stopped Dec 12 16:43:34 volumio-hq volumio[1315]: info: Disabling MyMusic plugin upnp Dec 12 16:43:34 volumio-hq sudo[6825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 12 16:43:34 volumio-hq sudo[6825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 16:43:34 volumio-hq systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Dec 12 16:43:36 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 12 16:43:37 volumio-hq volumio[1315]: info: Enabling MyMusic plugin upnp Dec 12 16:43:37 volumio-hq volumio[1315]: info: Enabling plugin upnp Dec 12 16:43:37 volumio-hq volumio[1315]: info: Loading plugin "upnp"... Dec 12 16:43:37 volumio-hq volumio[1315]: info: [1765529017844] Starting Upmpd Daemon Dec 12 16:43:37 volumio-hq volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 12 16:43:37 volumio-hq volumio[1315]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 16:43:37 volumio-hq volumio[1315]: Error: listen EADDRINUSE: address already in use :::6599 Dec 12 16:43:37 volumio-hq volumio[1315]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Dec 12 16:43:37 volumio-hq volumio[1315]: at listenInCluster (node:net:1920:12) Dec 12 16:43:37 volumio-hq volumio[1315]: at Server.listen (node:net:2008:7) Dec 12 16:43:37 volumio-hq volumio[1315]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 12 16:43:37 volumio-hq volumio[1315]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 12 16:43:37 volumio-hq volumio[1315]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 12 16:43:37 volumio-hq volumio[1315]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 12 16:43:37 volumio-hq volumio[1315]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 12 16:43:37 volumio-hq volumio[1315]: code: 'EADDRINUSE', Dec 12 16:43:37 volumio-hq volumio[1315]: errno: -98, Dec 12 16:43:37 volumio-hq volumio[1315]: syscall: 'listen', Dec 12 16:43:37 volumio-hq volumio[1315]: address: '::', Dec 12 16:43:37 volumio-hq volumio[1315]: port: 6599 Dec 12 16:43:37 volumio-hq volumio[1315]: } Dec 12 16:43:37 volumio-hq volumio[1315]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 16:43:38 volumio-hq sudo[6849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 16:42' Dec 12 16:43:38 volumio-hq sudo[6849]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"