Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO ProtocolHandler: [0x681640]: Received SetState message: Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO ProtocolHandler: [0x681640]: Playing state: Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO ProtocolHandler: [0x681640]: Playback position: 0 Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO ProtocolHandler: [0x681640]: Queue version: Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO ProtocolHandler: [0x681640]: Current track: Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO ProtocolHandler: [0x681640]: Next track: Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO MediaEngine: [0x680f00]: Seeking to time offset: 0ms Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO AudioRenderer: [0x681120]: Seek is in progress Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO AudioStreamManager: [0x680fe8]: [Stream 3]: Seeking to time offset: 0ms Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO UrlAudioSource: [0x7ddae8]: Seeking URL audio source to time offset: 0ms Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.138 [2438.2438] INFO SampleApp: Seek is in progress Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO SampleApp: Playback state changed: Seeking Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO StreamingEventReporter: [0x681a58]: Reporting streaming ended event Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO HttpDownloader: [0x681ab0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO ProtocolHandler: [0x681640]: Need to send state update Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO QConnectMessageSender: [0x7c8790]: Sending StateUpdated message Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO UrlAudioSource: [0x7ddae8]: Seeking input stream to byte offset: 532 Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.140 [2438.2438] INFO ContentFetcher: [0x867da8]: Adjusting download offset to: 532 Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.143 [2438.2438] INFO QwspMessageSender: [0x7cc780]: Sending Payload message Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO MediaEngine: [0x680f00]: Current stream has been seeked Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO AudioRenderer: [0x681120]: Seek has finished Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO SampleApp: [Stream 3]: Audio stream seeked: 0ms Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO SampleApp: Playback state changed: Playing Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO StreamingEventReporter: [0x681a58]: Reporting streaming started event Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO ProtocolHandler: [0x681640]: Need to send state update Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.307 [2438.2438] INFO QConnectMessageSender: [0x7c8790]: Sending StateUpdated message Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.308 [2438.2438] INFO QwspMessageSender: [0x7cc780]: Sending Payload message Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:04 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:04 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:04 volumio qobuz-connect[2438]: 20251211 20:17:04.355 [2438.2438] INFO HttpDownloader: [0x681ab0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:04 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:14 volumio qobuz-connect[2438]: 20251211 20:17:14.307 [2438.2438] INFO ProtocolHandler: [0x681640]: Need to send state update Dec 11 20:17:14 volumio qobuz-connect[2438]: 20251211 20:17:14.307 [2438.2438] INFO QConnectMessageSender: [0x7c8790]: Sending StateUpdated message Dec 11 20:17:14 volumio qobuz-connect[2438]: 20251211 20:17:14.307 [2438.2438] INFO QwspMessageSender: [0x7cc780]: Sending Payload message Dec 11 20:17:14 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:14 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:14 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:14 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:17:14 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:14 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:14 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:14 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:14 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Received SetState message: Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Playing state: Paused Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Playback position: Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Queue version: Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Current track: Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Next track: Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO MediaEngine: [0x680f00]: Pausing playback Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO AudioRenderer: [0x681120]: Pausing audio renderer Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO SampleApp: Pausing playback Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO SampleApp: Playback state changed: Paused Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO StreamingEventReporter: [0x681a58]: Reporting streaming ended event Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO HttpDownloader: [0x681ab0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO ProtocolHandler: [0x681640]: Need to send state update Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO QConnectMessageSender: [0x7c8790]: Sending StateUpdated message Dec 11 20:17:23 volumio qobuz-connect[2438]: 20251211 20:17:23.941 [2438.2438] INFO QwspMessageSender: [0x7cc780]: Sending Payload message Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:23 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:23 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:23 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:23 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:23 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:23 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 20:17:24 volumio volumio[1590]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 11 20:17:27 volumio go-librespot[1937]: time="2025-12-11T20:17:27+01:00" level=trace msg="sent dealer ping" Dec 11 20:17:27 volumio go-librespot[1937]: time="2025-12-11T20:17:27+01:00" level=trace msg="received dealer pong" Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:36 volumio volumio[1590]: info: Retrieving Cloud Streaming UI Dec 11 20:17:36 volumio volumio[1590]: info: Getting Tidal Cloud Configuration Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:36 volumio volumio[1590]: info: Getting Qobuz Cloud Configuration Dec 11 20:17:36 volumio volumio[1590]: info: Asking plugin for UI Config Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:36 volumio volumio[1590]: info: Getting Spotify Cloud Configuration Dec 11 20:17:36 volumio volumio[1590]: info: Asking plugin for UI Config Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:36 volumio volumio[1590]: info: Saving Spotify Acccount Dec 11 20:17:36 volumio volumio[1590]: info: Got it Dec 11 20:17:36 volumio volumio[1590]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 20:17:36 volumio volumio[1590]: info: Got Tidal Cloud Configuration Dec 11 20:17:36 volumio volumio[1590]: info: Got it Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 20:17:36 volumio volumio[1590]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 20:17:37 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 11 20:17:37 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 11 20:17:40 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:41 volumio volumio[1590]: info: CoreCommandRouter::volumioGetQueue Dec 11 20:17:41 volumio volumio[1590]: info: CoreStateMachine::getQueue Dec 11 20:17:41 volumio volumio[1590]: info: CorePlayQueue::getQueue Dec 11 20:17:42 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:17:42 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:17:42 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:17:42 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:17:42 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:17:42 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:17:44 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:17:45 volumio volumio[1590]: info: Getting Alsa Cards List without I2S DAC Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:17:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:17:46 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:17:46 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:17:46 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:17:46 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:17:46 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:17:46 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:17:51 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:17:51 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:17:51 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:17:51 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"hifiberry-dacplus-std","label":"HiFiBerry DAC Plus [Pi5]"}} Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:17:51 volumio volumio[1590]: info: Disabling I2S DAC: Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:17:51 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Dec 11 20:17:51 volumio volumio[1590]: verbose: UNSET VOLATILE: Service: qobuzconnect Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: Received stop Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.065 [2438.2438] INFO MediaEngine: [0x680f00]: Stopping playback, keeping tracks Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.066 [2438.2438] INFO AudioRenderer: [0x681120]: Stopping audio renderer Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.066 [2438.2438] INFO SampleApp: Stopping playback Dec 11 20:17:51 volumio vtcs[2518]: [2025-12-11 20:17:51.067] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 11 20:17:51 volumio vtcs[2518]: [2025-12-11 20:17:51.067] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.071 [2438.2438] INFO AudioStreamManager: [0x680fe8]: Clearing all streams Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.071 [2438.2438] INFO AudioStreamManager: [0x680fe8]: [Stream 3]: Disposing of audio stream Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.071 [2438.2438] INFO ContentFetcher: [0x867da8]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO AudioStreamManager: [0x680fe8]: New stream: 5 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO HttpDownloader: [0x839cb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=94bef6c86fbdb99047483f66f989a051&request_ts=1765480671&track_id=76474689 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO HttpDownloader: [0x876da0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=76474689 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO HttpDownloader: [0x813970]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e0799a51f49ad7752329a6beb5846c1b&request_ts=1765480671&track_id=76474689 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO AudioStreamManager: [0x680fe8]: [Stream 5]: Running audio stream Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO AudioStreamManager: [0x680fe8]: New stream: 6 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO HttpDownloader: [0x695cc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9cf5e14930fd06ca660a925fbf2675a2&request_ts=1765480671&track_id=76474690 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO HttpDownloader: [0x793b98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=76474690 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO HttpDownloader: [0x7c7d50]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=732c1f7993cd629b5ce5413069c744c7&request_ts=1765480671&track_id=76474690 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO SampleApp: Playback state changed: Stopped Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO ProtocolHandler: [0x681640]: Need to send state update Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO QConnectMessageSender: [0x7c8790]: Sending StateUpdated message Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.074 [2438.2438] INFO SampleApp: [Stream 3]: Audio stream has been disposed of Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.075 [2438.2438] INFO QwspMessageSender: [0x7cc780]: Sending Payload message Dec 11 20:17:51 volumio sudo[3431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:17:51 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:17:51 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:17:51 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 20:17:51 volumio systemd[1]: vtcs.service: Killing process 2529 (vtcs) with signal SIGKILL. Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:17:51 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 20:17:51 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:17:51 volumio sudo[3431]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: playback_stop received, deferred solved Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:17:51 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:17:51 volumio sudo[3446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:17:51 volumio sudo[3446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3439]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3451]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 11 20:17:51 volumio sudo[3446]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3451]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:17:51 volumio sudo[3454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: No Overlays Loaded Dec 11 20:17:51 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:17:51 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:51 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO HttpDownloader: [0x813970]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO HttpDownloader: [0x876da0]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO HttpDownloader: [0x839cb0]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO HttpDownloader: [0x7c7d50]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO HttpDownloader: [0x793b98]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO HttpDownloader: [0x695cc0]: Canceling download Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO CloudClient: [0x6814d8]: Disconnecting from the cloud Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.172 [2438.2438] INFO CloudClient: [0x6814d8]: Connection closed Dec 11 20:17:51 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 20:17:51 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio qobuz-connect[2438]: 20251211 20:17:51.176 [2438.2438] INFO SampleApp: Stopping Local configuration server Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:17:51 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:17:51 volumio volumio[1590]: SPOTIFY: SPOTIFY VOLUME 100 Dec 11 20:17:51 volumio volumio[1590]: SPOTIFY: VOLUMIO VOLUME 41 Dec 11 20:17:51 volumio volumio[1590]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 11 20:17:51 volumio volumio[1590]: info: Setting Spotify Volume from Volumio: 41 Dec 11 20:17:51 volumio volumio[1590]: info: Asound.conf file written Dec 11 20:17:51 volumio sudo[3467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 20:17:51 volumio sudo[3467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3467]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 20:17:51 volumio volumio[1590]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 20:17:51 volumio volumio[1590]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 20:17:51 volumio volumio[1590]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Dec 11 20:17:51 volumio volumio[1590]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Dec 11 20:17:51 volumio volumio[1590]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio sudo[3473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:17:51 volumio sudo[3473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:17:51 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3473]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:17:51 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:17:51 volumio sudo[3485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:17:51 volumio sudo[3487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3487]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio sudo[3493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:17:51 volumio sudo[3493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3485]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:17:51 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:17:51 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:17:51 volumio sudo[3501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:17:51 volumio sudo[3507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:17:51 volumio sudo[3517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:17:51 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio sudo[3526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:17:51 volumio sudo[3526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio sudo[3517]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3526]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:17:51 volumio sudo[3533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:17:51 volumio sudo[3533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:17:51 volumio sudo[3535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:17:51 volumio sudo[3535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:17:51 volumio sudo[3544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:17:51 volumio sudo[3544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3504]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:17:51 volumio sudo[3547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:17:51 volumio sudo[3547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:17:51 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:17:51 volumio sudo[3501]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Asound.conf file written Dec 11 20:17:51 volumio sudo[3507]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 20:17:51 volumio sudo[3554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3554]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 20:17:51 volumio volumio[1590]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 20:17:51 volumio volumio[1590]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 20:17:51 volumio volumio[1590]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Dec 11 20:17:51 volumio volumio[1590]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Dec 11 20:17:51 volumio volumio[1590]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:17:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio sudo[3560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:17:51 volumio sudo[3560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:17:51 volumio sudo[3563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3560]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:17:51 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:17:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:17:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:17:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:17:51 volumio systemd[1]: shairport-sync.service: Consumed 1.944s CPU time. Dec 11 20:17:51 volumio sudo[3574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:17:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:17:51 volumio sudo[3547]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:17:51 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:17:51 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:17:51 volumio sudo[3544]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:17:51 volumio sudo[3585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:17:51 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:17:51 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:17:51 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:17:51 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio sudo[3610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:17:51 volumio sudo[3610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:17:51 volumio sudo[3616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:17:51 volumio sudo[3616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:17:51 volumio sudo[3612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:17:51 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio sudo[3612]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:51 volumio sudo[3585]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:17:51 volumio sudo[3630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:17:51 volumio sudo[3629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:17:51 volumio sudo[3629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:17:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:17:51 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio sudo[3638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:17:51 volumio sudo[3638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:17:51 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:17:51 volumio sudo[3577]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:17:51 volumio sudo[3646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio sudo[3574]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3638]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:17:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:17:51 volumio sudo[3650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:17:51 volumio sudo[3650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:17:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:17:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:17:51 volumio sudo[3610]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:17:51 volumio sudo[3646]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:17:51 volumio sudo[3626]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3629]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:51 volumio sudo[3653]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:17:51 volumio sudo[3653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:17:51 volumio sudo[3653]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio mpd[3672]: 2025-12-11T20:17:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 20:17:52 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 20:17:52 volumio sudo[3475]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3616]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3563]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3493]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio volumio[1590]: error: MPD error: The expression evaluated to a falsy value: Dec 11 20:17:52 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:17:52 volumio volumio[1590]: error: The expression evaluated to a falsy value: Dec 11 20:17:52 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:17:52 volumio volumio[1590]: error: MPD error: The expression evaluated to a falsy value: Dec 11 20:17:52 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:17:52 volumio volumio[1590]: error: The expression evaluated to a falsy value: Dec 11 20:17:52 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:17:52 volumio volumio[1590]: error: MPD error: The expression evaluated to a falsy value: Dec 11 20:17:52 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:17:52 volumio volumio[1590]: error: The expression evaluated to a falsy value: Dec 11 20:17:52 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:17:52 volumio volumio[1590]: error: updateQueue error: null Dec 11 20:17:52 volumio qobuz-connect[2438]: 20251211 20:17:52.676 [2438.2438] INFO SampleApp: shat down connection on UNIX socket Dec 11 20:17:52 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 20:17:52 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:17:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:52 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 20:17:52 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:17:52 volumio systemd[1]: qobuz-connect.service: Consumed 35.920s CPU time. Dec 11 20:17:52 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:17:52 volumio sudo[3533]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3535]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3454]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3650]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio sudo[3630]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:52 volumio volumio[1590]: info: Executing endpoint qc_getconfig Dec 11 20:17:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.726 [3675.3675] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.728 [3675.3675] INFO VolumeManager: [0x1224368]: Setting new playback volume: 75 Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.728 [3675.3675] INFO VolumeManager: [0x1224368]: Setting new mute state: 0 Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.728 [3675.3675] INFO QobuzConnect: [0x1224d38]: Client initialized! Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.728 [3675.3675] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 20:17:52 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 20:17:52 volumio volumio[1590]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.734 [3675.3675] INFO LocalConfigManager: [0x1223c48]: Starting Local Configuration server Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.735 [3675.3675] INFO SampleApp: Starting Local configuration server Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.735 [3675.3675] INFO SampleApp: Connected to UNIX socket client 0x120e818 Dec 11 20:17:52 volumio volumio[1590]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Dec 11 20:17:52 volumio volumio[1590]: info: Sending Spotify command with payload to local API: /player/volume Dec 11 20:17:52 volumio go-librespot[1937]: time="2025-12-11T20:17:52+01:00" level=debug msg="update volume requested to 26869/65535" Dec 11 20:17:52 volumio qobuz-connect[3675]: 20251211 20:17:52.875 [3675.3675] INFO SampleApp: Playback volume changed: 75 Dec 11 20:17:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:53 volumio go-librespot[1937]: time="2025-12-11T20:17:53+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 11 20:17:53 volumio go-librespot[1937]: time="2025-12-11T20:17:53+01:00" level=trace msg="emitting websocket event: volume" Dec 11 20:17:53 volumio volumio[1590]: SPOTIFY: received: {"type":"volume","data":{"value":41,"max":100}} Dec 11 20:17:53 volumio volumio[1590]: SPOTIFY: RECEIVED SPOTIFY VOLUME 41 Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio sudo[3705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:17:54 volumio sudo[3705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:54 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 20:17:54 volumio sudo[3705]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:54 volumio volumio[1590]: info: Executing endpoint tc_getconfig Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 20:17:54 volumio vtcs[3708]: STARTING TidalConnect services, version: 1.5.2.56 Dec 11 20:17:54 volumio vtcs[3708]: STARTED TidalConnect services. Dec 11 20:17:54 volumio volumio[1590]: info: Executing endpoint tc_connect Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 20:17:54 volumio volumio[1590]: info: Connecting to TidalConnect Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:54 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:54 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:54 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:54 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:54 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:54 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:54 volumio volumio[1590]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:17:54 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:54 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:54 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:54 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:54 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:54 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:54 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:54 volumio volumio[1590]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 11 20:17:54 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:17:54 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio sudo[3737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio sudo[3737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:54 volumio sudo[3744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:17:54 volumio sudo[3744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:54 volumio sudo[3737]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:54 volumio sudo[3744]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio sudo[3757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:17:54 volumio sudo[3757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:54 volumio sudo[3757]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:17:54 volumio sudo[3769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:17:54 volumio sudo[3769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:54 volumio sudo[3769]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 20:17:55 volumio volumio[1590]: info: CoreStateMachine::getcurrentVolume Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:17:55 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:17:55 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:55 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:55 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:55 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:55 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:55 volumio volumio[1590]: info: CoreStateMachine::updateTrackBlock Dec 11 20:17:55 volumio volumio[1590]: info: CorePlayQueue::getTrackBlock Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:17:55 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:17:55 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:17:55 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:17:55 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:17:55 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:17:55 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:17:55 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:17:55 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:17:55 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:17:55 volumio sudo[3777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 20:17:55 volumio sudo[3777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:55 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 20:17:55 volumio sudo[3780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 20:17:55 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 20:17:55 volumio sudo[3780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:55 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 20:17:55 volumio sudo[3784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 20:17:55 volumio sudo[3784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:55 volumio sudo[3787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 20:17:55 volumio sudo[3787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:17:55 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 20:17:55 volumio mpd_monitor.sh[3783]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 20:17:55 volumio sudo[3777]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:55 volumio volumio[1590]: info: Successfully started MPD Monitor Dec 11 20:17:55 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 20:17:55 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 20:17:55 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 20:17:55 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 20:17:55 volumio sudo[3780]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:55 volumio mpd_monitor.sh[3793]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 20:17:55 volumio volumio[1590]: info: Successfully started MPD Monitor Dec 11 20:17:55 volumio sudo[3784]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:55 volumio sudo[3787]: pam_unix(sudo:session): session closed for user root Dec 11 20:17:55 volumio volumio[1590]: info: Successfully started MPD Monitor Dec 11 20:17:55 volumio volumio[1590]: info: Successfully started MPD Monitor Dec 11 20:17:57 volumio go-librespot[1937]: time="2025-12-11T20:17:57+01:00" level=trace msg="received accesspoint ping" Dec 11 20:17:57 volumio go-librespot[1937]: time="2025-12-11T20:17:57+01:00" level=trace msg="received accesspoint pong ack" Dec 11 20:17:57 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:17:57 volumio go-librespot[1937]: time="2025-12-11T20:17:57+01:00" level=trace msg="sent dealer ping" Dec 11 20:17:57 volumio go-librespot[1937]: time="2025-12-11T20:17:57+01:00" level=trace msg="received dealer pong" Dec 11 20:17:57 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:17:57 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:17:57 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:17:57 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:18:18 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:18 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:18 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:18 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:18 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:18 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:18 volumio vtcs[3708]: [2025-12-11 20:18:18.437] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 11 20:18:18 volumio vtcs[3708]: [2025-12-11 20:18:18.437] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 11 20:18:18 volumio sudo[3852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:18 volumio sudo[3852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:18 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:18 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:18 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:18 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 20:18:18 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 20:18:18 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 20:18:18 volumio sudo[3852]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:18 volumio sudo[3859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:18 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:18 volumio sudo[3867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:18 volumio sudo[3867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:18 volumio sudo[3859]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3867]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:18 volumio sudo[3876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:18 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 20:18:18 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:18 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:18 volumio qobuz-connect[3675]: 20251211 20:18:18.558 [3675.3675] INFO SampleApp: Stopping Local configuration server Dec 11 20:18:18 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:18 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:18 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:18 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:18 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:18 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:18 volumio sudo[3880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:18 volumio sudo[3880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio sudo[3880]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:18 volumio sudo[3882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:18 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 20:18:18 volumio sudo[3894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:18 volumio sudo[3894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio sudo[3892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:18 volumio sudo[3892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio sudo[3902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:18 volumio sudo[3902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:18 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:18 volumio sudo[3894]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3892]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:18 volumio sudo[3912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:18 volumio sudo[3902]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:18 volumio systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. Dec 11 20:18:18 volumio sudo[3916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:18 volumio sudo[3916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:18 volumio sudo[3912]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:18 volumio qobuz-connect[3675]: 20251211 20:18:18.762 [3675.3675] INFO SampleApp: shat down connection on UNIX socket Dec 11 20:18:18 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 20:18:18 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:18 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 20:18:18 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:18 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:18 volumio sudo[3916]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3876]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio sudo[3915]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:18 volumio sudo[3915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:18 volumio sudo[3915]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:18 volumio volumio[1590]: info: Executing endpoint qc_getconfig Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.814 [3931.3931] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 20:18:18 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 20:18:18 volumio volumio[1590]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.819 [3931.3931] INFO VolumeManager: [0x239368]: Setting new playback volume: 75 Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.819 [3931.3931] INFO VolumeManager: [0x239368]: Setting new mute state: 0 Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.819 [3931.3931] INFO QobuzConnect: [0x239d38]: Client initialized! Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.819 [3931.3931] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.828 [3931.3931] INFO LocalConfigManager: [0x238c48]: Starting Local Configuration server Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.828 [3931.3931] INFO SampleApp: Starting Local configuration server Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.828 [3931.3931] INFO SampleApp: Connected to UNIX socket client 0x223818 Dec 11 20:18:18 volumio qobuz-connect[3931]: 20251211 20:18:18.964 [3931.3931] INFO SampleApp: Playback volume changed: 75 Dec 11 20:18:18 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:18 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:19 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:19 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:19 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:19 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:19 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:19 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:19 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:19 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:19 volumio sudo[3951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:19 volumio sudo[3951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:19 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:19 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:19 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:19 volumio sudo[3951]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:19 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:19 volumio sudo[3959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:19 volumio sudo[3959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio mpd[3935]: 2025-12-11T20:18:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 20:18:19 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:19 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:19 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:19 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:19 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:19 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:19 volumio sudo[3965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:19 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:19 volumio sudo[3959]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio sudo[3965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:19 volumio sudo[3965]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio sudo[3974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:19 volumio sudo[3974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:19 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 20:18:19 volumio qobuz-connect[3931]: 20251211 20:18:19.547 [3931.3931] INFO SampleApp: Stopping Local configuration server Dec 11 20:18:19 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:19 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:19 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 20:18:19 volumio sudo[3882]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio sudo[3981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:19 volumio sudo[3981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio sudo[3979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:19 volumio sudo[3979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:19 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio sudo[3979]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 20:18:19 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:19 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:19 volumio sudo[3993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:19 volumio sudo[3993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:19 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:19 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:19 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:19 volumio sudo[3995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:19 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:19 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:19 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio sudo[4004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:19 volumio sudo[3993]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio sudo[4004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio sudo[4005]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:19 volumio sudo[4005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:19 volumio sudo[4005]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio sudo[3995]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:19 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:19 volumio sudo[4004]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:19 volumio sudo[4016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:19 volumio sudo[4018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:19 volumio sudo[4018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio sudo[4016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:19 volumio systemd[1]: shairport-sync.service: Consumed 1.134s CPU time. Dec 11 20:18:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:19 volumio sudo[4018]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:19 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:20 volumio mpd[4014]: 2025-12-11T20:18:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 20:18:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 20:18:20 volumio volumio[1590]: error: updateQueue error: null Dec 11 20:18:20 volumio sudo[3981]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:20 volumio volumio[1590]: error: updateQueue error: null Dec 11 20:18:20 volumio qobuz-connect[3931]: 20251211 20:18:20.832 [3931.3931] INFO SampleApp: shat down connection on UNIX socket Dec 11 20:18:20 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 20:18:20 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:20 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:20 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:20 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 20:18:20 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:20 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:20 volumio sudo[3974]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:20 volumio sudo[4016]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:20 volumio volumio[1590]: info: Executing endpoint qc_getconfig Dec 11 20:18:20 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.889 [4038.4038] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.891 [4038.4038] INFO VolumeManager: [0x14b5368]: Setting new playback volume: 75 Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.891 [4038.4038] INFO VolumeManager: [0x14b5368]: Setting new mute state: 0 Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.891 [4038.4038] INFO QobuzConnect: [0x14b5d38]: Client initialized! Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.891 [4038.4038] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 20:18:20 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 20:18:20 volumio volumio[1590]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.897 [4038.4038] INFO LocalConfigManager: [0x14b4c48]: Starting Local Configuration server Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.897 [4038.4038] INFO SampleApp: Starting Local configuration server Dec 11 20:18:20 volumio qobuz-connect[4038]: 20251211 20:18:20.897 [4038.4038] INFO SampleApp: Connected to UNIX socket client 0x149f818 Dec 11 20:18:21 volumio qobuz-connect[4038]: 20251211 20:18:21.039 [4038.4038] INFO SampleApp: Playback volume changed: 75 Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:21 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:21 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:21 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:21 volumio sudo[4053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:21 volumio sudo[4053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:21 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 20:18:21 volumio sudo[4053]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:21 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:21 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:21 volumio volumio[1590]: info: Executing endpoint tc_getconfig Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 20:18:21 volumio vtcs[4056]: STARTING TidalConnect services, version: 1.5.2.56 Dec 11 20:18:21 volumio vtcs[4056]: STARTED TidalConnect services. Dec 11 20:18:21 volumio volumio[1590]: info: Executing endpoint tc_connect Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 20:18:21 volumio volumio[1590]: info: Connecting to TidalConnect Dec 11 20:18:21 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:21 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:18:21 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:21 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:21 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:18:21 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:21 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:21 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:21 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:21 volumio volumio[1590]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 11 20:18:21 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:21 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:21 volumio sudo[4081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:21 volumio sudo[4081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:21 volumio sudo[4081]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:22 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:22 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 20:18:22 volumio volumio[1590]: info: CoreStateMachine::getcurrentVolume Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:18:22 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:22 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:22 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:22 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:22 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:22 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:22 volumio volumio[1590]: info: CoreStateMachine::updateTrackBlock Dec 11 20:18:22 volumio volumio[1590]: info: CorePlayQueue::getTrackBlock Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:18:22 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:22 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:22 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:22 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:22 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:22 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:22 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:22 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:22 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:22 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:22 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:22 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:22 volumio sudo[4098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:22 volumio sudo[4098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:22 volumio sudo[4098]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:22 volumio sudo[4102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 20:18:22 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:22 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 20:18:22 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 20:18:22 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 20:18:22 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 20:18:22 volumio mpd_monitor.sh[4104]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 20:18:22 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:22 volumio volumio[1590]: info: Successfully started MPD Monitor Dec 11 20:18:22 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:22 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:22 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:22 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:22 volumio sudo[4117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:22 volumio sudo[4117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:22 volumio sudo[4117]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::getcurrentVolume Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:18:23 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::updateTrackBlock Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrackBlock Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:18:23 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:23 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:23 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 11 20:18:23 volumio volumio[1590]: info: CoreCommandRouter::volumioPlay Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::play index undefined Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: info: CoreStateMachine::startPlaybackTimer Dec 11 20:18:23 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:23 volumio volumio[1590]: info: ControllerMpd::resume Dec 11 20:18:23 volumio volumio[1590]: verbose: ControllerMpd::sendMpdCommand play Dec 11 20:18:23 volumio volumio[1590]: info: sendMpdCommand play took 1 milliseconds Dec 11 20:18:23 volumio sudo[4140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 20:18:23 volumio sudo[4140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:23 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 20:18:23 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 20:18:23 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 20:18:23 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 20:18:23 volumio sudo[4140]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:23 volumio mpd_monitor.sh[4142]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 20:18:23 volumio volumio[1590]: info: Successfully started MPD Monitor Dec 11 20:18:24 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:18:24 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:18:24 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:18:24 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:18:24 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:18:24 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:18:24 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:18:24 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:18:25 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:18:25 volumio volumio[1590]: info: TidalConnect service started! Dec 11 20:18:27 volumio go-librespot[1937]: time="2025-12-11T20:18:27+01:00" level=trace msg="sent dealer ping" Dec 11 20:18:27 volumio go-librespot[1937]: time="2025-12-11T20:18:27+01:00" level=trace msg="received dealer pong" Dec 11 20:18:28 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:28 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:28 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 11 20:18:28 volumio volumio[1590]: info: CoreCommandRouter::volumioPlay Dec 11 20:18:28 volumio volumio[1590]: info: CoreStateMachine::play index undefined Dec 11 20:18:28 volumio volumio[1590]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 20:18:28 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:28 volumio volumio[1590]: info: CoreStateMachine::startPlaybackTimer Dec 11 20:18:28 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:28 volumio volumio[1590]: info: ControllerMpd::resume Dec 11 20:18:28 volumio volumio[1590]: verbose: ControllerMpd::sendMpdCommand play Dec 11 20:18:28 volumio volumio[1590]: info: sendMpdCommand play took 0 milliseconds Dec 11 20:18:43 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:43 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:45 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:18:45 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:18:45 volumio volumio[1590]: info: Executing endpoint metavolumio Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:45 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:48 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:48 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:48 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:48 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:48 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:48 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:48 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:48 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:48 volumio vtcs[4056]: [2025-12-11 20:18:48.369] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 11 20:18:48 volumio vtcs[4056]: [2025-12-11 20:18:48.373] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 11 20:18:48 volumio sudo[4204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:48 volumio sudo[4204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:48 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:48 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:48 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:48 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 20:18:48 volumio systemd[1]: vtcs.service: Killing process 4063 (vtcs) with signal SIGKILL. Dec 11 20:18:48 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 20:18:48 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 20:18:48 volumio sudo[4204]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio sudo[4211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:48 volumio sudo[4211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:48 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:48 volumio sudo[4218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:48 volumio sudo[4218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:48 volumio sudo[4211]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio sudo[4218]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:48 volumio sudo[4228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:48 volumio sudo[4228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:48 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:48 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:48 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:48 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 20:18:48 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:48 volumio qobuz-connect[4038]: 20251211 20:18:48.486 [4038.4038] INFO SampleApp: Stopping Local configuration server Dec 11 20:18:48 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:48 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:48 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:48 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:48 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:48 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:48 volumio sudo[4233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:48 volumio sudo[4233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio sudo[4233]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio sudo[4236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:48 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:48 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:48 volumio sudo[4236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:48 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 20:18:48 volumio sudo[4245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:48 volumio sudo[4245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio sudo[4250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:48 volumio sudo[4250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio sudo[4245]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:48 volumio sudo[4258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:48 volumio sudo[4258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:48 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:48 volumio sudo[4267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:48 volumio sudo[4267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:48 volumio sudo[4250]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio sudo[4258]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio sudo[4270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:48 volumio sudo[4270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:48 volumio systemd[1]: shairport-sync.service: Consumed 1.565s CPU time. Dec 11 20:18:48 volumio sudo[4269]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:48 volumio sudo[4269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:48 volumio sudo[4269]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:48 volumio sudo[4267]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:48 volumio volumio[1590]: (node:1590) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 11 20:18:48 volumio volumio[1590]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 11 20:18:48 volumio volumio[1590]: (node:1590) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 11 20:18:48 volumio volumio[1590]: (node:1590) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 11 20:18:48 volumio volumio[1590]: (node:1590) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 11 20:18:48 volumio volumio[1590]: (node:1590) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 11 20:18:48 volumio qobuz-connect[4038]: 20251211 20:18:48.926 [4038.4038] INFO SampleApp: shat down connection on UNIX socket Dec 11 20:18:48 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 20:18:48 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:48 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:48 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 20:18:48 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:48 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:48 volumio sudo[4228]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio sudo[4270]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:48 volumio volumio[1590]: info: Executing endpoint qc_getconfig Dec 11 20:18:48 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 20:18:48 volumio qobuz-connect[4290]: 20251211 20:18:48.995 [4290.4290] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 20:18:48 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 20:18:48 volumio volumio[1590]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 20:18:48 volumio qobuz-connect[4290]: 20251211 20:18:48.998 [4290.4290] INFO VolumeManager: [0xdac368]: Setting new playback volume: 75 Dec 11 20:18:48 volumio qobuz-connect[4290]: 20251211 20:18:48.998 [4290.4290] INFO VolumeManager: [0xdac368]: Setting new mute state: 0 Dec 11 20:18:48 volumio qobuz-connect[4290]: 20251211 20:18:48.999 [4290.4290] INFO QobuzConnect: [0xdacd38]: Client initialized! Dec 11 20:18:48 volumio qobuz-connect[4290]: 20251211 20:18:48.999 [4290.4290] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 20:18:49 volumio qobuz-connect[4290]: 20251211 20:18:49.005 [4290.4290] INFO LocalConfigManager: [0xdabc48]: Starting Local Configuration server Dec 11 20:18:49 volumio qobuz-connect[4290]: 20251211 20:18:49.005 [4290.4290] INFO SampleApp: Starting Local configuration server Dec 11 20:18:49 volumio qobuz-connect[4290]: 20251211 20:18:49.006 [4290.4290] INFO SampleApp: Connected to UNIX socket client 0xd96818 Dec 11 20:18:49 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:49 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:49 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:49 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:49 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:49 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio sudo[4306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:49 volumio sudo[4306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:49 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:49 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:49 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:49 volumio sudo[4306]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio qobuz-connect[4290]: 20251211 20:18:49.146 [4290.4290] INFO SampleApp: Playback volume changed: 75 Dec 11 20:18:49 volumio sudo[4316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:49 volumio sudo[4316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:49 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio sudo[4321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:49 volumio sudo[4321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio sudo[4316]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:49 volumio sudo[4321]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio sudo[4332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:49 volumio sudo[4332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:49 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:49 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:49 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:49 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:49 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 20:18:49 volumio qobuz-connect[4290]: 20251211 20:18:49.269 [4290.4290] INFO SampleApp: Stopping Local configuration server Dec 11 20:18:49 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:49 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:49 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:49 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio sudo[4335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:49 volumio sudo[4335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio sudo[4337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:49 volumio sudo[4337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio sudo[4335]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio sudo[4346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:49 volumio sudo[4346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:49 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio sudo[4357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:49 volumio sudo[4357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio sudo[4357]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:49 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:49 volumio sudo[4364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:49 volumio sudo[4364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio sudo[4346]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio sudo[4366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:49 volumio sudo[4366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:49 volumio sudo[4366]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:49 volumio sudo[4372]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:49 volumio sudo[4372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:49 volumio sudo[4372]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:49 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:49 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:49 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:49 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:49 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio sudo[4400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:49 volumio sudo[4400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:49 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:49 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:49 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:49 volumio sudo[4400]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio sudo[4407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:49 volumio sudo[4407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:49 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:49 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:49 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:49 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:49 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:49 volumio sudo[4415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:49 volumio sudo[4415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:49 volumio sudo[4407]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:49 volumio sudo[4415]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:49 volumio sudo[4425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:49 volumio sudo[4425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:49 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:49 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:49 volumio sudo[4428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:49 volumio sudo[4428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio sudo[4428]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:49 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:49 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:49 volumio sudo[4430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:49 volumio sudo[4430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:49 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:49 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:49 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:50 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:50 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:50 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:50 volumio sudo[4440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:50 volumio sudo[4442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:50 volumio sudo[4440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio sudo[4442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:50 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:50 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio sudo[4451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio sudo[4451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:50 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:50 volumio sudo[4460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:50 volumio sudo[4460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio sudo[4451]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:50 volumio sudo[4462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio sudo[4458]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:50 volumio sudo[4458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:50 volumio sudo[4442]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4458]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4440]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:50 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:50 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:50 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:50 volumio sudo[4460]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:50 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:50 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:50 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:50 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:50 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:50 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:50 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:50 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:50 volumio mpd[4466]: 2025-12-11T20:18:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 20:18:50 volumio sudo[4493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:50 volumio sudo[4493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:50 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:50 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:50 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:50 volumio sudo[4493]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:50 volumio sudo[4500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:50 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:50 volumio sudo[4508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:50 volumio sudo[4508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:50 volumio sudo[4500]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 20:18:50 volumio sudo[4430]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4337]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4236]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio sudo[4508]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio sudo[4520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:50 volumio sudo[4520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:50 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:50 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:50 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:50 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:50 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:50 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:51 volumio qobuz-connect[4290]: 20251211 20:18:51.023 [4290.4290] INFO SampleApp: shat down connection on UNIX socket Dec 11 20:18:51 volumio volumio[1590]: error: MPD error: The expression evaluated to a falsy value: Dec 11 20:18:51 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:18:51 volumio volumio[1590]: error: The expression evaluated to a falsy value: Dec 11 20:18:51 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:18:51 volumio volumio[1590]: error: MPD error: The expression evaluated to a falsy value: Dec 11 20:18:51 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:18:51 volumio volumio[1590]: error: The expression evaluated to a falsy value: Dec 11 20:18:51 volumio volumio[1590]: assert.ok(self.idling) Dec 11 20:18:51 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 20:18:51 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: error: updateQueue error: null Dec 11 20:18:51 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:51 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:51 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:51 volumio sudo[4364]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4462]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4520]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4332]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:51 volumio sudo[4425]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:51 volumio sudo[4527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:51 volumio sudo[4525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4525]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:51 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio sudo[4539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:51 volumio volumio[1590]: info: Executing endpoint qc_getconfig Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 20:18:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:51 volumio sudo[4547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:51 volumio sudo[4547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.158 [4522.4522] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.161 [4522.4522] INFO VolumeManager: [0x109c368]: Setting new playback volume: 75 Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.161 [4522.4522] INFO VolumeManager: [0x109c368]: Setting new mute state: 0 Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.161 [4522.4522] INFO QobuzConnect: [0x109cd38]: Client initialized! Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.161 [4522.4522] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.173 [4522.4522] INFO LocalConfigManager: [0x109bc48]: Starting Local Configuration server Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.173 [4522.4522] INFO SampleApp: Starting Local configuration server Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.173 [4522.4522] INFO SampleApp: Connected to UNIX socket client 0x1086818 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 20:18:51 volumio volumio[1590]: info: Discovery: Getting this device information Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 20:18:51 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:51 volumio volumio[1590]: verbose: New Socket.io Connection to 192.168.100.150:3000 from 192.168.100.211 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 20:18:51 volumio sudo[4561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:51 volumio sudo[4547]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:51 volumio sudo[4563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:51 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:51 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:51 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio sudo[4537]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4539]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:51 volumio systemd[1]: shairport-sync.service: Consumed 1.044s CPU time. Dec 11 20:18:51 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 20:18:51 volumio sudo[4562]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:51 volumio qobuz-connect[4522]: 20251211 20:18:51.288 [4522.4522] INFO SampleApp: Stopping Local configuration server Dec 11 20:18:51 volumio sudo[4562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:51 volumio sudo[4562]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:51 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio sudo[4561]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:51 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:51 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:51 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:51 volumio sudo[4581]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:51 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:51 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:51 volumio sudo[4610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:51 volumio sudo[4610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:51 volumio sudo[4610]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4605]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio sudo[4613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:51 volumio sudo[4613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:51 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:51 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:51 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:51 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:51 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:51 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:51 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio sudo[4633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:51 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:51 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:51 volumio sudo[4633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:51 volumio sudo[4633]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:51 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:51 volumio sudo[4647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:51 volumio sudo[4647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4641]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio volumio[1590]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 20:18:51 volumio volumio[1590]: info: Preparing to save Alsa Options, stopping services first Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioPause Dec 11 20:18:51 volumio volumio[1590]: info: CoreStateMachine::pause Dec 11 20:18:51 volumio volumio[1590]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO K11 R2R"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio sudo[4647]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:51 volumio sudo[4657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:51 volumio volumio[1590]: info: Setting mixer PCM for card FiiO K11 R2R Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio sudo[4666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 20:18:51 volumio volumio[1590]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO K11 R2R Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 20:18:51 volumio volumio[1590]: info: Disabling external Volume Control Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 20:18:51 volumio sudo[4666]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:51 volumio sudo[4674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:51 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:51 volumio sudo[4674]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:51 volumio sudo[4681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:51 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:51 volumio sudo[4681]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:51 volumio sudo[4695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:51 volumio sudo[4695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:51 volumio sudo[4697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio sudo[4697]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:51 volumio sudo[4698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:51 volumio sudo[4698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:51 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:51 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:51 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:51 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:51 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:51 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:51 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:52 volumio sudo[4709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:52 volumio sudo[4709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:52 volumio sudo[4713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:52 volumio sudo[4713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio sudo[4720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:52 volumio sudo[4720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio sudo[4720]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio sudo[4709]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:52 volumio sudo[4713]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio sudo[4723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:52 volumio sudo[4723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:52 volumio sudo[4721]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:52 volumio sudo[4721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:52 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:52 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:52 volumio sudo[4721]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:52 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:52 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 20:18:52 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:52 volumio sudo[4746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:52 volumio sudo[4746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 20:18:52 volumio sudo[4746]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::getcurrentVolume Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:18:52 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:52 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: Starting Shairport Sync Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: Executing endpoint tc_getconfig Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 20:18:52 volumio sudo[4776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:52 volumio sudo[4776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio vtcs[4758]: STARTING TidalConnect services, version: 1.5.2.56 Dec 11 20:18:52 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:52 volumio vtcs[4758]: STARTED TidalConnect services. Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::updateTrackBlock Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrackBlock Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 20:18:52 volumio sudo[4778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 20:18:52 volumio sudo[4778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio volumio[1590]: info: Asound.conf file unchanged, so no further update is needed Dec 11 20:18:52 volumio volumio[1590]: info: Output device has changed, restarting MPD Dec 11 20:18:52 volumio volumio[1590]: info: Output device has changed, restarting Shairport Sync Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:52 volumio sudo[4776]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio sudo[4789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 20:18:52 volumio sudo[4789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio sudo[4791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 20:18:52 volumio sudo[4791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio sudo[4789]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 20:18:52 volumio volumio[1590]: info: QobuzConnect: setDeactiveState invoked Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 20:18:52 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:52 volumio systemd[1]: shairport-sync.service: Consumed 1.186s CPU time. Dec 11 20:18:52 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 20:18:52 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 20:18:52 volumio volumio[1590]: info: Preparing to generate the ALSA configuration file Dec 11 20:18:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 20:18:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 20:18:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 20:18:52 volumio sudo[4802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:52 volumio sudo[4802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio sudo[4806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 20:18:52 volumio sudo[4806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:52 volumio sudo[4812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 20:18:52 volumio sudo[4812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 20:18:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 20:18:52 volumio volumio[1590]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 20:18:52 volumio volumio[1590]: info: Reading ALSA contributions from plugins. Dec 11 20:18:52 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 20:18:52 volumio volumio[1590]: info: Executing endpoint tc_connect Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 20:18:52 volumio volumio[1590]: info: Connecting to TidalConnect Dec 11 20:18:52 volumio sudo[4815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:52 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio sudo[4778]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: MPD Permissions set Dec 11 20:18:52 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::servicePushState Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 11 20:18:52 volumio sudo[4822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 20:18:52 volumio sudo[4822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 20:18:52 volumio systemd[1]: vtcs.service: Killing process 4784 (vtcs) with signal SIGKILL. Dec 11 20:18:52 volumio sudo[4802]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 20:18:52 volumio sudo[4812]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:52 volumio sudo[4806]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:52 volumio sudo[4841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 20:18:52 volumio sudo[4841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 20:18:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 20:18:52 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: Shairport-Sync Started Dec 11 20:18:52 volumio sudo[4817]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 20:18:52 volumio sudo[4817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 20:18:52 volumio volumio[1590]: info: VolumeController:: Volume=41 Mute =false Dec 11 20:18:52 volumio volumio[1590]: info: CoreStateMachine::pushState Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioPushState Dec 11 20:18:52 volumio sudo[4817]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output update for this device Dec 11 20:18:52 volumio volumio[1590]: info: MRS: Pushing multiroomSync output Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::volumioGetState Dec 11 20:18:52 volumio sudo[4822]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:52 volumio volumio[1590]: info: CorePlayQueue::getTrack 0 Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 20:18:52 volumio volumio[1590]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 11 20:18:52 volumio volumio[1590]: info: TidalConnect service stoped! Dec 11 20:18:52 volumio volumio[1590]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 11 20:18:52 volumio volumio[1590]: Job for vtcs.service canceled. Dec 11 20:18:52 volumio volumio[1590]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Dec 11 20:18:52 volumio volumio[1590]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 20:18:52 volumio volumio[1590]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 11 20:18:52 volumio volumio[1590]: Job for vtcs.service canceled. Dec 11 20:18:52 volumio volumio[1590]: at ChildProcess.exithandler (node:child_process:421:12) Dec 11 20:18:52 volumio volumio[1590]: at ChildProcess.emit (node:events:514:28) Dec 11 20:18:52 volumio volumio[1590]: at maybeClose (node:internal/child_process:1105:16) Dec 11 20:18:52 volumio volumio[1590]: at Socket. (node:internal/child_process:457:11) Dec 11 20:18:52 volumio volumio[1590]: at Socket.emit (node:events:514:28) Dec 11 20:18:52 volumio volumio[1590]: at Pipe. (node:net:337:12) { Dec 11 20:18:52 volumio volumio[1590]: code: 1, Dec 11 20:18:52 volumio volumio[1590]: killed: false, Dec 11 20:18:52 volumio volumio[1590]: signal: null, Dec 11 20:18:52 volumio volumio[1590]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 11 20:18:52 volumio volumio[1590]: stdout: '', Dec 11 20:18:52 volumio volumio[1590]: stderr: 'Job for vtcs.service canceled.\n' Dec 11 20:18:52 volumio volumio[1590]: } Dec 11 20:18:52 volumio volumio[1590]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 20:18:53 volumio qobuz-connect[4522]: 20251211 20:18:53.179 [4522.4522] INFO SampleApp: shat down connection on UNIX socket Dec 11 20:18:53 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 20:18:53 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:53 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 20:18:53 volumio sudo[4563]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:53 volumio sudo[4695]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:53 volumio sudo[4657]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:53 volumio sudo[4613]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:53 volumio sudo[4841]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:53 volumio sudo[4723]: pam_unix(sudo:session): session closed for user root Dec 11 20:18:53 volumio sudo[4874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 20:17' Dec 11 20:18:53 volumio sudo[4874]: 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"