Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:03 room volumio[1340]: info: Retrieving Cloud Streaming UI Dec 14 13:07:03 room volumio[1340]: info: Getting Tidal Cloud Configuration Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:03 room volumio[1340]: info: Getting Qobuz Cloud Configuration Dec 14 13:07:03 room volumio[1340]: info: Asking plugin for UI Config Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:03 room volumio[1340]: info: Getting Spotify Cloud Configuration Dec 14 13:07:03 room volumio[1340]: info: Asking plugin for UI Config Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:03 room volumio[1340]: info: Saving Spotify Acccount Dec 14 13:07:03 room volumio[1340]: info: Got it Dec 14 13:07:03 room volumio[1340]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 13:07:03 room volumio[1340]: info: Got Tidal Cloud Configuration Dec 14 13:07:03 room volumio[1340]: info: Got it Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 13:07:03 room volumio[1340]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 14 13:07:03 room volumio[1340]: info: Received Get System Version Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 13:07:03 room volumio[1340]: info: Received Get System Info Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 13:07:03 room volumio[1340]: info: Discovery: Getting this device information Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand update Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:04 room volumio[1340]: info: Dec 14 13:07:04 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:04 room volumio[1340]: info: sendMpdCommand update took 1 milliseconds Dec 14 13:07:04 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:04 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:04 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:04 room volumio[1340]: info: Dec 14 13:07:04 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:04 room volumio[1340]: info: sendMpdCommand status took 8 milliseconds Dec 14 13:07:04 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:04 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:04 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:04 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:04 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::syncState currentStatus play Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::stPlaybackTimer Dec 14 13:07:04 room volumio[1340]: info: ------------------------------ 9ms Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:04 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:04 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:04 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:04 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:04 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:04 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:04 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:04 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:04 room volumio[1340]: info: No code Dec 14 13:07:04 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:04 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:04 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:04 room volumio[1340]: info: ------------------------------ 13ms Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:04 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Clipping Monitor started Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Clipping Monitor started Dec 14 13:07:04 room volumio[1340]: info: FusionDsp - Clipping Monitor started Dec 14 13:07:06 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:06 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:06 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:06 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:06 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:06 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:07 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand rescan Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:08 room volumio[1340]: info: Dec 14 13:07:08 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:08 room volumio[1340]: info: sendMpdCommand rescan took 1 milliseconds Dec 14 13:07:08 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:08 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:08 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:08 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:08 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:08 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:08 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:08 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:08 room volumio[1340]: info: No code Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:08 room volumio[1340]: info: ------------------------------ 10ms Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:08 room volumio[1340]: info: Dec 14 13:07:08 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:08 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:08 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:08 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:08 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:08 room volumio[1340]: info: sendMpdCommand status took 2 milliseconds Dec 14 13:07:08 room volumio[1340]: info: sendMpdCommand status took 2 milliseconds Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:08 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:08 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:08 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:08 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:08 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:08 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:08 room volumio[1340]: info: No code Dec 14 13:07:08 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:08 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:08 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:08 room volumio[1340]: info: ------------------------------ 8ms Dec 14 13:07:08 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:08 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:08 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:11 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:13 room volumio[1340]: info: Disabling MyMusic plugin airplay_emulation Dec 14 13:07:13 room volumio[1340]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Dec 14 13:07:13 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 13:07:13 room volumio[1340]: info: Disabling plugin airplay_emulation Dec 14 13:07:13 room volumio[1340]: info: Done. Dec 14 13:07:13 room sudo[5637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Dec 14 13:07:13 room sudo[5637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:13 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:13 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:13 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:13 room systemd[1]: shairport-sync.service: Consumed 7.485s CPU time. Dec 14 13:07:13 room sudo[5637]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:13 room volumio[1340]: info: Shairport-Sync Stopped Dec 14 13:07:15 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:15 room volumio[1340]: info: Enabling MyMusic plugin airplay_emulation Dec 14 13:07:15 room volumio[1340]: info: Enabling plugin airplay_emulation Dec 14 13:07:15 room volumio[1340]: info: Loading plugin "airplay_emulation"... Dec 14 13:07:15 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:15 room volumio[1340]: info: PLUGIN START: airplay_emulation Dec 14 13:07:15 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:15 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:15 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:15 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:15 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:15 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:15 room volumio[1340]: info: Done. Dec 14 13:07:15 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:15 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:15 room sudo[5649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:15 room sudo[5649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:15 room sudo[5651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:15 room sudo[5651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:15 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:15 room sudo[5649]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:15 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:15 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:15 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:15 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:15 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:15 room sudo[5651]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:15 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:17 room volumio[1340]: info: Disabling MyMusic plugin upnp Dec 14 13:07:17 room sudo[5677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 14 13:07:17 room sudo[5677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:17 room systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Dec 14 13:07:18 room volumio[1340]: info: Disabling MyMusic plugin airplay_emulation Dec 14 13:07:18 room volumio[1340]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Dec 14 13:07:18 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 13:07:18 room volumio[1340]: info: Disabling plugin airplay_emulation Dec 14 13:07:18 room volumio[1340]: info: Done. Dec 14 13:07:18 room sudo[5681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Dec 14 13:07:18 room sudo[5681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:18 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:18 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:18 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:18 room systemd[1]: shairport-sync.service: Consumed 1.526s CPU time. Dec 14 13:07:18 room sudo[5681]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:18 room volumio[1340]: info: Shairport-Sync Stopped Dec 14 13:07:19 room volumio[1340]: info: Disabling MyMusic plugin upnp_browser Dec 14 13:07:19 room volumio[1340]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Dec 14 13:07:19 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 13:07:19 room volumio[1340]: info: Disabling plugin upnp_browser Dec 14 13:07:19 room volumio[1340]: info: Done. Dec 14 13:07:19 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:23 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:24 room volumio[1340]: info: Disabling MyMusic plugin bluetooth Dec 14 13:07:24 room volumio[1340]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [FUNC] onStop Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Dec 14 13:07:24 room sudo[5705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Dec 14 13:07:24 room sudo[5705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:24 room systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Dec 14 13:07:24 room volumiobt[5708]: INFO [BTSTART] Disconnecting all Bluetooth devices... Dec 14 13:07:24 room bluetoothd[1054]: Adv Monitor app :1.48 disconnected from D-Bus Dec 14 13:07:24 room volumiobt[5713]: INFO [BTSTART] Unregistering Bluetooth agent... Dec 14 13:07:24 room volumiobt[5714]: No agent is registered Dec 14 13:07:24 room volumiobt[5714]: [NEW] Media /org/bluez/hci0 Dec 14 13:07:24 room volumiobt[5714]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 14 13:07:24 room volumiobt[5714]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 14 13:07:24 room volumiobt[5714]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 14 13:07:24 room bluetoothd[1054]: Adv Monitor app :1.49 disconnected from D-Bus Dec 14 13:07:24 room volumiobt[5715]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Dec 14 13:07:24 room volumiobt[5717]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Dec 14 13:07:24 room systemd[1]: volumiobt.service: Deactivated successfully. Dec 14 13:07:24 room systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Dec 14 13:07:24 room sudo[5705]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Dec 14 13:07:24 room volumio[1340]: verbose: UNSET VOLATILE: Service: undefined Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::resetVolumioState Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::getcurrentVolume Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::resetVolumioState Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::getcurrentVolume Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:24 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:24 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:24 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:24 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioStop Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::stop Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:24 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:24 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:24 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:24 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:24 room volumio[1340]: info: CoreCommandRouter::volumioStop Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::stop Dec 14 13:07:24 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:24 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:24 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:24 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:24 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:24 room bluetoothd[1054]: Path / reserved for Adv Monitor app :1.50 Dec 14 13:07:24 room bluetoothd[1054]: Adv Monitor app :1.50 disconnected from D-Bus Dec 14 13:07:24 room volumio[1340]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered off Dec 14 13:07:24 room volumio[1340]: info: Disabling plugin bluetooth Dec 14 13:07:26 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:26 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:26 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:26 room volumio[1340]: info: Disabling MyMusic plugin multiroom Dec 14 13:07:26 room volumio[1340]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Dec 14 13:07:26 room volumio[1340]: info: Disabling plugin multiroom Dec 14 13:07:26 room volumio[1340]: info: Preparing to generate the ALSA configuration file Dec 14 13:07:26 room volumio[1340]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 13:07:26 room volumio[1340]: info: Reading ALSA contributions from plugins. Dec 14 13:07:26 room volumio[1340]: info: Asound.conf file written Dec 14 13:07:26 room sudo[5726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 13:07:26 room sudo[5726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5726]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room volumio[1340]: info: Output device has changed, restarting MPD Dec 14 13:07:26 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room sudo[5732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 13:07:26 room sudo[5732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5732]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 13:07:26 room sudo[5734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:26 room volumio[1340]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:26 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:26 room volumio[1340]: info: Relinquishing Volumio State Dec 14 13:07:26 room vtcs[2701]: [2025-12-14 13:07:26.531] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 13:07:26 room vtcs[2701]: [close:85] Entering Dec 14 13:07:26 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 13:07:26 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:26 room sudo[5744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:26 room sudo[5744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:26 room sudo[5754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 13:07:26 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 13:07:26 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 13:07:26 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 13:07:26 room sudo[5765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 13:07:26 room sudo[5765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room volumio[1340]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 14 13:07:26 room volumio[1340]: info: MPD Permissions set Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:26 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:26 room vtcs[2701]: [feedThread:276] Exiting Dec 14 13:07:26 room vtcs[2701]: [close:100] Exiting Dec 14 13:07:26 room sudo[5781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:26 room sudo[5781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room volumio[1340]: info: Preparing to generate the ALSA configuration file Dec 14 13:07:26 room vtcs[2701]: [close:85] Entering Dec 14 13:07:26 room vtcs[2701]: [close:100] Exiting Dec 14 13:07:26 room sudo[5783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:26 room sudo[5783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room volumio[1340]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 13:07:26 room volumio[1340]: info: Reading ALSA contributions from plugins. Dec 14 13:07:26 room volumio[1340]: info: Asound.conf file unchanged, so no further update is needed Dec 14 13:07:26 room volumio[1340]: info: Output device has changed, restarting MPD Dec 14 13:07:26 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room sudo[5787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 13:07:26 room sudo[5787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5787]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 13:07:26 room sudo[5789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:26 room volumio[1340]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:26 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:26 room volumio[1340]: info: Relinquishing Volumio State Dec 14 13:07:26 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 13:07:26 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 13:07:26 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:26 room sudo[5801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:26 room sudo[5801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5765]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room sudo[5808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:26 room sudo[5808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 13:07:26 room sudo[5813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:26 room volumio[1340]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 14 13:07:26 room volumio[1340]: info: MPD Permissions set Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 13:07:26 room sudo[5781]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 13:07:26 room sudo[5783]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 13:07:26 room sudo[5817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 13:07:26 room sudo[5817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 13:07:26 room sudo[5817]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 13:07:26 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 13:07:26 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:26 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:26 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 13:07:26 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:26 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 13:07:26 room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 13:07:26 room qobuz-connect[2679]: 20251214 13:07:26.599 [2679.2679] INFO SampleApp: Stopping Local configuration server Dec 14 13:07:26 room volumio[1340]: (node:1340) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 13:07:26 room volumio[1340]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 14 13:07:26 room volumio[1340]: (node:1340) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 13:07:26 room volumio[1340]: (node:1340) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 13:07:26 room volumio[1340]: (node:1340) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 13:07:26 room volumio[1340]: (node:1340) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 13:07:26 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:26 room sudo[5829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 13:07:26 room sudo[5829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room systemd[1]: vtcs.service: Deactivated successfully. Dec 14 13:07:26 room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 13:07:26 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:26 room systemd[1]: vtcs.service: Consumed 15.233s CPU time. Dec 14 13:07:26 room sudo[5848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:26 room sudo[5848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:26 room sudo[5850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:26 room sudo[5831]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 13:07:26 room sudo[5831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 13:07:26 room sudo[5808]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5831]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5754]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5801]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5744]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:26 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:26 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:26 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:26 room sudo[5850]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5848]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:26 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:26 room mpd[5856]: 2025-12-14T13:07:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 13:07:26 room systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 13:07:26 room sudo[5789]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5734]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room volumio[1340]: error: MPD error: The expression evaluated to a falsy value: Dec 14 13:07:26 room volumio[1340]: assert.ok(self.idling) Dec 14 13:07:26 room volumio[1340]: error: The expression evaluated to a falsy value: Dec 14 13:07:26 room volumio[1340]: assert.ok(self.idling) Dec 14 13:07:26 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:26 room qobuz-connect[2679]: 20251214 13:07:26.760 [2679.2679] INFO SampleApp: shat down connection on UNIX socket Dec 14 13:07:26 room volumio[1340]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 13:07:26 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:26 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:26 room systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 13:07:26 room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 13:07:26 room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 13:07:26 room sudo[5813]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room sudo[5829]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:26 room volumio[1340]: info: Executing endpoint qc_getconfig Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.821 [5879.5879] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 13:07:26 room volumio[1340]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 13:07:26 room volumio[1340]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.822 [5879.5879] INFO VolumeManager: [0x565233cb2e70]: Setting new playback volume: 75 Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.822 [5879.5879] INFO VolumeManager: [0x565233cb2e70]: Setting new mute state: 0 Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.822 [5879.5879] INFO QobuzConnect: [0x565233cb4090]: Client initialized! Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.822 [5879.5879] INFO SampleApp: Starting Avahi advertising, name: Room, service name: _qobuz-connect._tcp Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.825 [5879.5879] INFO LocalConfigManager: [0x565233cb2160]: Starting Local Configuration server Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.825 [5879.5879] INFO SampleApp: Starting Local configuration server Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.825 [5879.5879] INFO SampleApp: Connected to UNIX socket client 0x565233c88bb0 Dec 14 13:07:26 room qobuz-connect[5879]: 20251214 13:07:26.970 [5879.5879] INFO SampleApp: Playback volume changed: 75 Dec 14 13:07:26 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:26 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:27 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:27 room systemd[1]: upmpdcli.service: State 'stop-sigterm' timed out. Killing. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 2734 (upmpdcli) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 2735 (n/a) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 2741 (n/a) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 2742 (n/a) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 2743 (n/a) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 2753 (upmpdcli) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 4654 (n/a) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Killing process 5036 (n/a) with signal SIGKILL. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Main process exited, code=killed, status=9/KILL Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Failed with result 'timeout'. Dec 14 13:07:27 room systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 13:07:27 room systemd[1]: upmpdcli.service: Consumed 1.021s CPU time. Dec 14 13:07:27 room sudo[5677]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:27 room volumio[1340]: info: Disabling plugin upnp Dec 14 13:07:27 room volumio[1340]: info: Done. Dec 14 13:07:29 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:29 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:29 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:29 room volumio[1340]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:29 room volumio[1340]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:29 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:29 room volumio[1340]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:29 room volumio[1340]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:29 room sudo[5907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 13:07:29 room sudo[5907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:29 room sudo[5910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 13:07:29 room sudo[5910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:29 room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 13:07:29 room sudo[5907]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:29 room sudo[5910]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:29 room volumio[1340]: info: Executing endpoint tc_getconfig Dec 14 13:07:29 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 13:07:29 room vtcs[5913]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 13:07:29 room vtcs[5913]: STARTED TidalConnect services. Dec 14 13:07:29 room volumio[1340]: info: Executing endpoint tc_connect Dec 14 13:07:29 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 13:07:29 room volumio[1340]: info: Connecting to TidalConnect Dec 14 13:07:29 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:29 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:29 room volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 13:07:29 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:29 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:29 room volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 13:07:29 room sudo[5931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 13:07:29 room sudo[5931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:29 room sudo[5933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 13:07:29 room sudo[5933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:29 room systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 13:07:29 room systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 13:07:29 room systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 13:07:29 room systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 13:07:29 room sudo[5931]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:29 room sudo[5933]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:29 room volumio[1340]: info: Successfully started MPD Monitor Dec 14 13:07:29 room mpd_monitor.sh[5936]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 13:07:29 room volumio[1340]: info: Successfully started MPD Monitor Dec 14 13:07:30 room volumio[1340]: info: Enabling MyMusic plugin multiroom Dec 14 13:07:30 room volumio[1340]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Dec 14 13:07:30 room volumio[1340]: info: Enabling plugin multiroom Dec 14 13:07:30 room volumio[1340]: info: Preparing to generate the ALSA configuration file Dec 14 13:07:30 room volumio[1340]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 13:07:30 room volumio[1340]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 13:07:30 room volumio[1340]: info: Reading ALSA contributions from plugins. Dec 14 13:07:30 room volumio[1340]: info: Asound.conf file written Dec 14 13:07:30 room sudo[5956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 13:07:30 room sudo[5956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[5956]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting MPD Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room sudo[5962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 13:07:30 room sudo[5962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:30 room volumio[1340]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room sudo[5964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 13:07:30 room sudo[5964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[5962]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:30 room volumio[1340]: info: Relinquishing Volumio State Dec 14 13:07:30 room vtcs[5913]: [2025-12-14 13:07:30.448] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 13:07:30 room vtcs[5913]: [2025-12-14 13:07:30.448] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 13:07:30 room sudo[5974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:30 room sudo[5974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:30 room volumio[1340]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 14 13:07:30 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 13:07:30 room volumio[1340]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 13:07:30 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room sudo[5985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:30 room sudo[5985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: MPD Permissions set Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room sudo[5992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 13:07:30 room sudo[5992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:30 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 13:07:30 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:30 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 13:07:30 room sudo[6003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:30 room sudo[6003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: Preparing to generate the ALSA configuration file Dec 14 13:07:30 room sudo[6007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:30 room sudo[6007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: Done. Dec 14 13:07:30 room volumio[1340]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 13:07:30 room volumio[1340]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 13:07:30 room volumio[1340]: info: Reading ALSA contributions from plugins. Dec 14 13:07:30 room volumio[1340]: info: Asound.conf file written Dec 14 13:07:30 room sudo[5992]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 13:07:30 room sudo[6012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:30 room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 13:07:30 room sudo[6011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 13:07:30 room sudo[6011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:30 room systemd[1]: shairport-sync.service: Consumed 1.536s CPU time. Dec 14 13:07:30 room systemd[1]: vtcs.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 13:07:30 room sudo[6011]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[5974]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[5985]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 13:07:30 room sudo[6004]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 13:07:30 room sudo[6004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 13:07:30 room qobuz-connect[5879]: 20251214 13:07:30.500 [5879.5879] INFO SampleApp: Stopping Local configuration server Dec 14 13:07:30 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:30 room sudo[6004]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6003]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6007]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting MPD Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room sudo[6024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 13:07:30 room sudo[6024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6024]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:30 room volumio[1340]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 13:07:30 room sudo[6030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 13:07:30 room sudo[6030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:30 room volumio[1340]: info: Relinquishing Volumio State Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 13:07:30 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 13:07:30 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 13:07:30 room sudo[6050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:30 room sudo[6050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 13:07:30 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:30 room volumio[1340]: info: Preparing to generate the ALSA configuration file Dec 14 13:07:30 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 13:07:30 room sudo[6059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:30 room sudo[6059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room sudo[6050]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 13:07:30 room volumio[1340]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 13:07:30 room volumio[1340]: info: Reading ALSA contributions from plugins. Dec 14 13:07:30 room volumio[1340]: info: MPD Permissions set Dec 14 13:07:30 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:30 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:30 room sudo[6057]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 13:07:30 room sudo[6057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 13:07:30 room sudo[6057]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 13:07:30 room sudo[6067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 13:07:30 room sudo[6059]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:30 room sudo[6067]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Asound.conf file unchanged, so no further update is needed Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting MPD Dec 14 13:07:30 room sudo[6083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 13:07:30 room sudo[6083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:30 room sudo[6081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room sudo[6084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:30 room sudo[6084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 13:07:30 room sudo[6088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 13:07:30 room sudo[6091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6088]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:30 room volumio[1340]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:30 room volumio[1340]: info: Relinquishing Volumio State Dec 14 13:07:30 room volumio[1340]: info: Output device has changed, restarting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:30 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:30 room sudo[6102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:30 room sudo[6102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 13:07:30 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:30 room sudo[6081]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6084]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 13:07:30 room sudo[6109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:07:30 room sudo[6109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 13:07:30 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 13:07:30 room volumio[1340]: info: MPD Permissions set Dec 14 13:07:30 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:30 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:30 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room sudo[6118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 13:07:30 room sudo[6118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 13:07:30 room sudo[6118]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:30 room volumio[1340]: info: Starting Shairport Sync Dec 14 13:07:30 room sudo[6145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:30 room sudo[6145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6109]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 13:07:30 room sudo[6147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 13:07:30 room sudo[6143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:30 room sudo[6102]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 13:07:30 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:30 room sudo[6117]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 13:07:30 room sudo[6117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 13:07:30 room sudo[6117]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 13:07:30 room sudo[6145]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6147]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:30 room volumio[1340]: info: Shairport-Sync Started Dec 14 13:07:30 room mpd[6156]: 2025-12-14T13:07:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 13:07:30 room systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 13:07:30 room sudo[6030]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[5964]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6091]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: error: MPD error: The expression evaluated to a falsy value: Dec 14 13:07:30 room volumio[1340]: assert.ok(self.idling) Dec 14 13:07:30 room volumio[1340]: error: The expression evaluated to a falsy value: Dec 14 13:07:30 room volumio[1340]: assert.ok(self.idling) Dec 14 13:07:30 room volumio[1340]: error: MPD error: The expression evaluated to a falsy value: Dec 14 13:07:30 room volumio[1340]: assert.ok(self.idling) Dec 14 13:07:30 room volumio[1340]: error: The expression evaluated to a falsy value: Dec 14 13:07:30 room volumio[1340]: assert.ok(self.idling) Dec 14 13:07:30 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:30 room qobuz-connect[5879]: 20251214 13:07:30.834 [5879.5879] INFO SampleApp: shat down connection on UNIX socket Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: setDeactiveState invoked Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:30 room systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 13:07:30 room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 13:07:30 room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 13:07:30 room sudo[6083]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6012]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room sudo[6143]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:30 room volumio[1340]: info: Executing endpoint qc_getconfig Dec 14 13:07:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.876 [6173.6173] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 13:07:30 room volumio[1340]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.877 [6173.6173] INFO VolumeManager: [0x559a1d4a6e70]: Setting new playback volume: 75 Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.877 [6173.6173] INFO VolumeManager: [0x559a1d4a6e70]: Setting new mute state: 0 Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.877 [6173.6173] INFO QobuzConnect: [0x559a1d4a8090]: Client initialized! Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.877 [6173.6173] INFO SampleApp: Starting Avahi advertising, name: Room, service name: _qobuz-connect._tcp Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.880 [6173.6173] INFO LocalConfigManager: [0x559a1d4a6160]: Starting Local Configuration server Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.880 [6173.6173] INFO SampleApp: Starting Local configuration server Dec 14 13:07:30 room qobuz-connect[6173]: 20251214 13:07:30.880 [6173.6173] INFO SampleApp: Connected to UNIX socket client 0x559a1d47cbb0 Dec 14 13:07:31 room qobuz-connect[6173]: 20251214 13:07:31.026 [6173.6173] INFO SampleApp: Playback volume changed: 75 Dec 14 13:07:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:31 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:31 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:32 room volumio[1340]: info: TidalConnect service started! Dec 14 13:07:32 room volumio[1340]: info: TidalConnect service started! Dec 14 13:07:33 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:33 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:33 room volumio[1340]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:33 room volumio[1340]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:33 room sudo[6191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 13:07:33 room sudo[6191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:33 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:33 room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 13:07:33 room sudo[6191]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:33 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:33 room volumio[1340]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:33 room volumio[1340]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:33 room sudo[6205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 13:07:33 room sudo[6205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:33 room sudo[6205]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:33 room volumio[1340]: info: Executing endpoint tc_getconfig Dec 14 13:07:33 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 13:07:33 room vtcs[6194]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 13:07:33 room vtcs[6194]: STARTED TidalConnect services. Dec 14 13:07:33 room volumio[1340]: info: Executing endpoint tc_connect Dec 14 13:07:33 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 13:07:33 room volumio[1340]: info: Connecting to TidalConnect Dec 14 13:07:33 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:33 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:33 room volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 13:07:33 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:33 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:33 room volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 13:07:33 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:33 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:07:33 room volumio[1340]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:33 room volumio[1340]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:07:33 room sudo[6231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 13:07:33 room sudo[6231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:33 room sudo[6231]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:33 room sudo[6236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 13:07:33 room sudo[6236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:33 room sudo[6238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 13:07:33 room sudo[6238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:33 room sudo[6240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 13:07:33 room sudo[6240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:07:33 room systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 13:07:33 room systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 13:07:33 room systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 13:07:33 room systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 13:07:33 room sudo[6236]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:33 room sudo[6240]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:33 room volumio[1340]: info: Successfully started MPD Monitor Dec 14 13:07:33 room volumio[1340]: info: Successfully started MPD Monitor Dec 14 13:07:33 room mpd_monitor.sh[6244]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 13:07:33 room sudo[6238]: pam_unix(sudo:session): session closed for user root Dec 14 13:07:33 room volumio[1340]: info: Successfully started MPD Monitor Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand update Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: Dec 14 13:07:34 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand update took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: Dec 14 13:07:34 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: Dec 14 13:07:34 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:34 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: No code Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:34 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: No code Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:34 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: No code Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: ------------------------------ 22ms Dec 14 13:07:34 room volumio[1340]: info: ------------------------------ 22ms Dec 14 13:07:34 room volumio[1340]: info: ------------------------------ 22ms Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: Dec 14 13:07:34 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: Dec 14 13:07:34 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: Dec 14 13:07:34 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 4 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:34 room volumio[1340]: info: sendMpdCommand status took 3 milliseconds Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:34 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:34 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: No code Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:34 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: No code Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:34 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: No code Dec 14 13:07:34 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:34 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:34 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:34 room volumio[1340]: info: ------------------------------ 21ms Dec 14 13:07:34 room volumio[1340]: info: ------------------------------ 21ms Dec 14 13:07:34 room volumio[1340]: info: ------------------------------ 21ms Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:34 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand rescan Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: Dec 14 13:07:35 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand rescan took 0 milliseconds Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: Dec 14 13:07:35 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: Dec 14 13:07:35 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:35 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: No code Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:35 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: No code Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: ------------------------------ 19ms Dec 14 13:07:35 room volumio[1340]: info: ------------------------------ 19ms Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: Dec 14 13:07:35 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 19 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 19 milliseconds Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:35 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: No code Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: ------------------------------ 26ms Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: Dec 14 13:07:35 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: Dec 14 13:07:35 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 14 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 14 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:35 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:35 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:35 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: No code Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:35 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: No code Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:35 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: No code Dec 14 13:07:35 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:35 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:35 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:35 room volumio[1340]: info: ------------------------------ 34ms Dec 14 13:07:35 room volumio[1340]: info: ------------------------------ 27ms Dec 14 13:07:35 room volumio[1340]: info: ------------------------------ 27ms Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:35 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:35 room volumio[1340]: info: MRS: Getting audio outputs on start Dec 14 13:07:35 room volumio[1340]: info: MRS: Requesting all other devices output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand rescan Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: Dec 14 13:07:36 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand rescan took 0 milliseconds Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: Dec 14 13:07:36 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: Dec 14 13:07:36 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 0 milliseconds Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:36 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: No code Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: ------------------------------ 7ms Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: Dec 14 13:07:36 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 7 milliseconds Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: Command Router : Notfying DB Updatetrue Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:36 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: No code Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:36 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: No code Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: ------------------------------ 17ms Dec 14 13:07:36 room volumio[1340]: info: ------------------------------ 17ms Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: Dec 14 13:07:36 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: Dec 14 13:07:36 room volumio[1340]: ---------------------------- MPD announces state update: update Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::getState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand status Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 12 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 12 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 2 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 2 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 2 milliseconds Dec 14 13:07:36 room volumio[1340]: info: sendMpdCommand status took 2 milliseconds Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: Command Router : Notfying DB Updatefalse Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::Close All Modals sent Dec 14 13:07:36 room volumio[1340]: verbose: ControllerMpd::parseState Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:36 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: No code Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:36 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: No code Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: ControllerMpd::pushState Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 14 13:07:36 room volumio[1340]: verbose: CURRENT POSITION 0 Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState stateService stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::syncState currentStatus stop Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: No code Dec 14 13:07:36 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:36 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:36 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:36 room volumio[1340]: info: ------------------------------ 39ms Dec 14 13:07:36 room volumio[1340]: info: ------------------------------ 29ms Dec 14 13:07:36 room volumio[1340]: info: ------------------------------ 29ms Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:36 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:36 room volumio[1340]: info: TidalConnect service started! Dec 14 13:07:36 room volumio[1340]: info: TidalConnect service started! Dec 14 13:07:36 room volumio[1340]: info: TidalConnect service started! Dec 14 13:07:37 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:37 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:37 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 14 13:07:37 room volumio[1340]: info: CoreCommandRouter::volumioPlay Dec 14 13:07:37 room volumio[1340]: info: CoreStateMachine::play index undefined Dec 14 13:07:37 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:37 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:37 room volumio[1340]: info: CoreStateMachine::startPlaybackTimer Dec 14 13:07:37 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:37 room volumio[1340]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 13:07:37 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 13:07:37 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 13:07:37 room volumio[1340]: verbose: ControllerMpd::clearAddPlayTracks USB/Nuevo_vol/Chayanne/Chayanne/FLAC (16bit-44.1kHz)/03 - Fuiste un Trozo de Hielo en la.flac Dec 14 13:07:37 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 13:07:37 room volumio[1340]: info: sendMpdCommand stop took 0 milliseconds Dec 14 13:07:37 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 13:07:37 room volumio[1340]: info: sendMpdCommand clear took 0 milliseconds Dec 14 13:07:37 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand add "USB/Nuevo_vol/Chayanne/Chayanne/FLAC (16bit-44.1kHz)/03 - Fuiste un Trozo de Hielo en la.flac" Dec 14 13:07:37 room volumio[1340]: info: Dec 14 13:07:37 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:37 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:37 room volumio[1340]: info: Dec 14 13:07:37 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:37 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:37 room volumio[1340]: info: Dec 14 13:07:37 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:37 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:37 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:37 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:37 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:37 room volumio[1340]: info: ------------------------------ 0ms Dec 14 13:07:37 room volumio[1340]: info: ------------------------------ 0ms Dec 14 13:07:37 room volumio[1340]: info: ------------------------------ 0ms Dec 14 13:07:38 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:38 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:38 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 14 13:07:38 room volumio[1340]: info: CoreCommandRouter::volumioPlay Dec 14 13:07:38 room volumio[1340]: info: CoreStateMachine::play index undefined Dec 14 13:07:38 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:38 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:38 room volumio[1340]: info: CoreStateMachine::startPlaybackTimer Dec 14 13:07:38 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:38 room volumio[1340]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 13:07:38 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 13:07:38 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 13:07:38 room volumio[1340]: verbose: ControllerMpd::clearAddPlayTracks USB/Nuevo_vol/Chayanne/Chayanne/FLAC (16bit-44.1kHz)/03 - Fuiste un Trozo de Hielo en la.flac Dec 14 13:07:38 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 13:07:38 room volumio[1340]: info: sendMpdCommand stop took 1 milliseconds Dec 14 13:07:38 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 13:07:38 room volumio[1340]: info: sendMpdCommand clear took 0 milliseconds Dec 14 13:07:38 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand add "USB/Nuevo_vol/Chayanne/Chayanne/FLAC (16bit-44.1kHz)/03 - Fuiste un Trozo de Hielo en la.flac" Dec 14 13:07:38 room volumio[1340]: info: Dec 14 13:07:38 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:38 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:38 room volumio[1340]: info: Dec 14 13:07:38 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:38 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:38 room volumio[1340]: info: Dec 14 13:07:38 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:38 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:38 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:38 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:38 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:38 room volumio[1340]: info: ------------------------------ 0ms Dec 14 13:07:38 room volumio[1340]: info: ------------------------------ 0ms Dec 14 13:07:38 room volumio[1340]: info: ------------------------------ 0ms Dec 14 13:07:40 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:40 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:40 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 14 13:07:40 room volumio[1340]: info: CoreCommandRouter::volumioPlay Dec 14 13:07:40 room volumio[1340]: info: CoreStateMachine::play index undefined Dec 14 13:07:40 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:40 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:40 room volumio[1340]: info: CoreStateMachine::startPlaybackTimer Dec 14 13:07:40 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:40 room volumio[1340]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 13:07:40 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 13:07:40 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 13:07:40 room volumio[1340]: verbose: ControllerMpd::clearAddPlayTracks USB/Nuevo_vol/Chayanne/Chayanne/FLAC (16bit-44.1kHz)/03 - Fuiste un Trozo de Hielo en la.flac Dec 14 13:07:40 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 13:07:40 room volumio[1340]: info: sendMpdCommand stop took 0 milliseconds Dec 14 13:07:40 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 13:07:40 room volumio[1340]: info: sendMpdCommand clear took 0 milliseconds Dec 14 13:07:40 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand add "USB/Nuevo_vol/Chayanne/Chayanne/FLAC (16bit-44.1kHz)/03 - Fuiste un Trozo de Hielo en la.flac" Dec 14 13:07:40 room volumio[1340]: info: Dec 14 13:07:40 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:40 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:40 room volumio[1340]: info: Dec 14 13:07:40 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:40 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:40 room volumio[1340]: info: Dec 14 13:07:40 room volumio[1340]: ---------------------------- MPD announces system playlist update Dec 14 13:07:40 room volumio[1340]: info: Ignoring MPD Status Update Dec 14 13:07:40 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:40 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:40 room volumio[1340]: error: updateQueue error: null Dec 14 13:07:40 room volumio[1340]: info: ------------------------------ 1ms Dec 14 13:07:40 room volumio[1340]: info: ------------------------------ 1ms Dec 14 13:07:40 room volumio[1340]: info: ------------------------------ 1ms Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:43 room volumio[1340]: info: Currently active: mpd Dec 14 13:07:43 room volumio[1340]: info: Stopping currently active service Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioStop Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::stop Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:43 room volumio[1340]: info: TidalConnect Active Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:07:43 room volumio[1340]: info: Currently active: mpd Dec 14 13:07:43 room volumio[1340]: info: Setting Volatile state to tidalconnect Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:43 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:43 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:43 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:43 room vtcs[6194]: [2025-12-14 13:07:43.953] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Dec 14 13:07:43 room vtcs[6194]: [2025-12-14 13:07:43.953] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Dec 14 13:07:43 room volumio[1340]: info: Pushing metadata Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:43 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:43 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:43 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:43 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:07:43 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:43 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:43 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:44 room volumio[1340]: info: Pushing metadata Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:44 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:44 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:44 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:44 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:44 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:44 room vtcs[6194]: [close:85] Entering Dec 14 13:07:44 room vtcs[6194]: [close:100] Exiting Dec 14 13:07:44 room vtcs[6194]: [open:50] Opening with sample rate: 192000, type: 3 Dec 14 13:07:44 room vtcs[6194]: [start:106] Entering Dec 14 13:07:44 room vtcs[6194]: [start:113] Reopening PCM device... Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:44 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 13:07:44 room vtcs[6194]: [start:115] PCM opened successfully. Dec 14 13:07:44 room vtcs[6194]: [start:120] Exiting Dec 14 13:07:44 room vtcs[6194]: [feedThread:223] Entering Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 13:07:44 room vtcs[6194]: [2025-12-14 13:07:44.822] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:44 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:44 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:44 room volumio[1340]: info: FusionDsp - Clipping Monitor started Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:07:45 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:07:45 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:45 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:07:45 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:07:45 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:07:45 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:07:45 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:45 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:45 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:07:45 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:51 room volumio[1340]: info: Retrieving Cloud Streaming UI Dec 14 13:07:51 room volumio[1340]: info: Getting Tidal Cloud Configuration Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:51 room volumio[1340]: info: Getting Qobuz Cloud Configuration Dec 14 13:07:51 room volumio[1340]: info: Asking plugin for UI Config Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:51 room volumio[1340]: info: Getting Spotify Cloud Configuration Dec 14 13:07:51 room volumio[1340]: info: Asking plugin for UI Config Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:51 room volumio[1340]: info: Saving Spotify Acccount Dec 14 13:07:51 room volumio[1340]: info: Got it Dec 14 13:07:51 room volumio[1340]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 13:07:51 room volumio[1340]: info: Got Tidal Cloud Configuration Dec 14 13:07:51 room volumio[1340]: info: Got it Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:51 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 14 13:07:55 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:07:57 room volumio[1340]: info: Enabling MyMusic plugin upnp Dec 14 13:07:57 room volumio[1340]: info: Enabling plugin upnp Dec 14 13:07:57 room volumio[1340]: info: Loading plugin "upnp"... Dec 14 13:07:57 room volumio[1340]: info: [1765739277236] Starting Upmpd Daemon Dec 14 13:07:57 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 13:07:57 room volumio[1340]: info: Done. Dec 14 13:07:59 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:03 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:05 room volumio[1340]: info: Disabling MyMusic plugin tidalconnect Dec 14 13:08:05 room volumio[1340]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Dec 14 13:08:05 room volumio[1340]: info: Relinquishing Volumio State Dec 14 13:08:05 room volumio[1340]: verbose: UNSET VOLATILE: Service: tidalconnect Dec 14 13:08:05 room volumio[1340]: info: unSetVolatile called Dec 14 13:08:05 room volumio[1340]: info: Relinquishing Volumio State to another service Dec 14 13:08:05 room vtcs[6194]: [2025-12-14 13:08:05.448] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 13:08:05 room vtcs[6194]: [2025-12-14 13:08:05.448] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 13:08:05 room vtcs[6194]: [close:85] Entering Dec 14 13:08:05 room volumio[1340]: info: Disabling plugin tidalconnect Dec 14 13:08:05 room sudo[6405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:08:05 room sudo[6405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:08:05 room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 13:08:05 room systemd[1]: vtcs.service: Killing process 6210 (vtcs) with signal SIGKILL. Dec 14 13:08:05 room systemd[1]: vtcs.service: Deactivated successfully. Dec 14 13:08:05 room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 13:08:05 room systemd[1]: vtcs.service: Consumed 1.119s CPU time. Dec 14 13:08:05 room sudo[6405]: pam_unix(sudo:session): session closed for user root Dec 14 13:08:07 room sudo[6415]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 13:08:07 room sudo[6415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:08:07 room sudo[6417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 13:08:07 room sudo[6417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:08:07 room sudo[6415]: pam_unix(sudo:session): session closed for user root Dec 14 13:08:07 room sudo[6417]: pam_unix(sudo:session): session closed for user root Dec 14 13:08:07 room sudo[6419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 14 13:08:07 room sudo[6419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:08:07 room systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 14 13:08:07 room sudo[6419]: pam_unix(sudo:session): session closed for user root Dec 14 13:08:07 room volumio[1340]: info: Upmpdcli Daemon Started Dec 14 13:08:07 room upmpdcli[6438]: writing RSA key Dec 14 13:08:07 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:07 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:08 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:08:09 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:11 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:11 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:13 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:13 room volumio[1340]: info: Enabling MyMusic plugin tidalconnect Dec 14 13:08:13 room volumio[1340]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Dec 14 13:08:13 room volumio[1340]: info: Enabling plugin tidalconnect Dec 14 13:08:13 room volumio[1340]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 14 13:08:13 room sudo[6468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 13:08:13 room sudo[6468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:08:13 room sudo[6468]: pam_unix(sudo:session): session closed for user root Dec 14 13:08:15 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:15 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:16 room volumio[1340]: info: TidalConnect service stoped! Dec 14 13:08:16 room volumio[1340]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:08:16 room volumio[1340]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 13:08:16 room sudo[6486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 13:08:16 room sudo[6486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 13:08:16 room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 13:08:16 room sudo[6486]: pam_unix(sudo:session): session closed for user root Dec 14 13:08:16 room volumio[1340]: info: Executing endpoint tc_getconfig Dec 14 13:08:16 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 13:08:16 room vtcs[6489]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 13:08:16 room vtcs[6489]: STARTED TidalConnect services. Dec 14 13:08:16 room volumio[1340]: info: Executing endpoint tc_connect Dec 14 13:08:16 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 13:08:16 room volumio[1340]: info: Connecting to TidalConnect Dec 14 13:08:16 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:16 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:16 room volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 13:08:16 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:16 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:16 room volumio[1340]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 13:08:17 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:19 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:19 room volumio[1340]: info: TidalConnect service started! Dec 14 13:08:19 room volumio[1340]: info: Done. Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:08:20 room volumio[1340]: info: Retrieving Cloud Streaming UI Dec 14 13:08:20 room volumio[1340]: info: Getting Tidal Cloud Configuration Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:08:20 room volumio[1340]: info: Getting Qobuz Cloud Configuration Dec 14 13:08:20 room volumio[1340]: info: Asking plugin for UI Config Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:08:20 room volumio[1340]: info: Getting Spotify Cloud Configuration Dec 14 13:08:20 room volumio[1340]: info: Asking plugin for UI Config Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:08:20 room volumio[1340]: info: Saving Spotify Acccount Dec 14 13:08:20 room volumio[1340]: info: Got it Dec 14 13:08:20 room volumio[1340]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 13:08:20 room volumio[1340]: info: Got Tidal Cloud Configuration Dec 14 13:08:20 room volumio[1340]: info: Got it Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:08:20 room volumio[1340]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 13:08:21 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:21 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 14 13:08:21 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:23 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:24 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:08:25 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:25 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:27 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:29 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:29 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:30 room volumio[1340]: info: Currently active: mpd Dec 14 13:08:30 room volumio[1340]: info: Stopping currently active service Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioStop Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::stop Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::stPlaybackTimer Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::updateTrackBlock Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrackBlock Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::serviceStop Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::serviceStop Dec 14 13:08:30 room volumio[1340]: info: ControllerMpd::stop Dec 14 13:08:30 room volumio[1340]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 13:08:30 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:08:30 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:08:30 room volumio[1340]: info: sendMpdCommand stop took 2 milliseconds Dec 14 13:08:30 room volumio[1340]: info: TidalConnect Active Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:30 room volumio[1340]: info: CorePlayQueue::getTrack 0 Dec 14 13:08:30 room volumio[1340]: info: Currently active: mpd Dec 14 13:08:30 room volumio[1340]: info: Setting Volatile state to tidalconnect Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:30 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:30 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:30 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:30 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:30 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:08:30 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:30 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:30 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room vtcs[6489]: [2025-12-14 13:08:31.030] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Dec 14 13:08:31 room vtcs[6489]: [2025-12-14 13:08:31.030] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Dec 14 13:08:31 room volumio[1340]: info: Pushing metadata Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: info: Pushing metadata Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is not playing Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Clipped samples monitor stopped Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room vtcs[6489]: [close:85] Entering Dec 14 13:08:31 room vtcs[6489]: [close:100] Exiting Dec 14 13:08:31 room vtcs[6489]: [open:50] Opening with sample rate: 192000, type: 3 Dec 14 13:08:31 room vtcs[6489]: [start:106] Entering Dec 14 13:08:31 room vtcs[6489]: [start:113] Reopening PCM device... Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:31 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:31 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:31 room volumio[1340]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 13:08:31 room vtcs[6489]: [start:115] PCM opened successfully. Dec 14 13:08:31 room vtcs[6489]: [start:120] Exiting Dec 14 13:08:31 room vtcs[6489]: [feedThread:223] Entering Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - ---- read samplerate from file: 192000 Dec 14 13:08:31 room volumio[1340]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 13:08:32 room vtcs[6489]: [2025-12-14 13:08:32.129] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:32 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:32 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:32 room volumio[1340]: info: FusionDsp - Clipping Monitor started Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::servicePushState Dec 14 13:08:32 room volumio[1340]: info: CoreStateMachine::pushState Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::volumioPushState Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::volumioGetState Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output update for this device Dec 14 13:08:32 room volumio[1340]: info: MRS: Pushing multiroomSync output Dec 14 13:08:32 room volumio[1340]: info: FusionDsp - Volumio is playing Dec 14 13:08:32 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:08:32 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:08:32 room volumio[1340]: info: Executing endpoint metavolumio Dec 14 13:08:32 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 13:08:33 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:37 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:41 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:45 room volumio[1340]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 14 13:08:54 room volumio[1340]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 14 13:08:54 room volumio[1340]: info: CoreStateMachine::removeQueueItem Dec 14 13:08:54 room volumio[1340]: info: CoreStateMachine::stop Dec 14 13:08:54 room volumio[1340]: info: CoreStateMachine::serviceStop Dec 14 13:08:54 room volumio[1340]: info: CoreCommandRouter::serviceStop Dec 14 13:08:54 room volumio[1340]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 13:08:54 room vtcs[6489]: [close:85] Entering Dec 14 13:08:54 room volumio[1340]: TypeError: Cannot read properties of undefined (reading 'then') Dec 14 13:08:54 room volumio[1340]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Dec 14 13:08:54 room volumio[1340]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Dec 14 13:08:54 room volumio[1340]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Dec 14 13:08:54 room volumio[1340]: at Socket.emit (node:events:514:28) Dec 14 13:08:54 room volumio[1340]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 14 13:08:54 room volumio[1340]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 14 13:08:54 room volumio[1340]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 13:08:54 room vtcs[6489]: [feedThread:276] Exiting Dec 14 13:08:54 room vtcs[6489]: [close:100] Exiting Dec 14 13:08:54 room sudo[6649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 13:07' Dec 14 13:08:54 room sudo[6649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"