-- Logs begin at Sat 2025-07-26 11:23:19 CEST, end at Mon 2025-09-08 18:09:54 CEST. -- Sep 08 18:08:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 18:08:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 18:08:24 rivoplus volumio[3291]: info: Discovery: Getting this device information Sep 08 18:08:24 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:08:24 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:08:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 18:08:39 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 18:08:39 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 18:08:39 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:08:41 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 18:08:41 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:08:43 rivoplus ntpd[4846]: 217.147.223.78 local addr 192.168.178.104 -> Sep 08 18:08:44 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 18:08:44 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:08:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 18:08:48 rivoplus volumio[3291]: info: TuneIn handleBrowseUri: tunein/local Sep 08 18:08:48 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:08:48 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:08:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 18:08:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 18:08:54 rivoplus volumio[3291]: info: Discovery: Getting this device information Sep 08 18:08:54 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:08:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:08:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 18:09:00 rivoplus volumio[3291]: Searching plugin music_service/webradio Sep 08 18:09:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 08 18:09:01 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 08 18:09:06 rivoplus volumio[3291]: info: Saving local item radio-favourites Sep 08 18:09:06 rivoplus volumio[3291]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s25243","favourite":false} Sep 08 18:09:07 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::ClearQueue Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::stop Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:07 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:07 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::serviceStop Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::serviceStop Sep 08 18:09:07 rivoplus volumio[3291]: info: [1757347747926] ControllerTidal::stop Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 18:09:07 rivoplus volumio[3291]: info: ControllerMpd::stop Sep 08 18:09:07 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::clearPlayQueue Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreStateMachine::addQueueItems Sep 08 18:09:07 rivoplus volumio[3291]: info: CorePlayQueue::addQueueItems Sep 08 18:09:07 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:09:07 rivoplus volumio[3291]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25243 Sep 08 18:09:07 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 18:09:07 rivoplus volumio[3291]: info: Sep 08 18:09:07 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:07 rivoplus volumio[3291]: info: sendMpdCommand stop took 57 milliseconds Sep 08 18:09:07 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:07 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:07 rivoplus volumio[3291]: info: Sep 08 18:09:07 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:07 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:07 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:07 rivoplus volumio[3291]: info: Sep 08 18:09:07 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:07 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:07 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:07 rivoplus volumio[3291]: info: Sep 08 18:09:07 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:07 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:07 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand status took 11 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand status took 8 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand status took 6 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand status took 4 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:08 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:08 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"127657189&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/127657189&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 18:09:08 rivoplus volumio[3291]: verbose: CURRENT POSITION 27 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: No code Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:08 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"127657189&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/127657189&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 18:09:08 rivoplus volumio[3291]: verbose: CURRENT POSITION 27 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: No code Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:08 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"127657189&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/127657189&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 18:09:08 rivoplus volumio[3291]: verbose: CURRENT POSITION 27 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: No code Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 27 Sep 08 18:09:08 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"127657189&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/127657189&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 18:09:08 rivoplus volumio[3291]: verbose: CURRENT POSITION 27 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: No code Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:08 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:08 rivoplus volumio[3291]: info: ------------------------------ 188ms Sep 08 18:09:08 rivoplus volumio[3291]: info: ------------------------------ 184ms Sep 08 18:09:08 rivoplus volumio[3291]: info: ------------------------------ 184ms Sep 08 18:09:08 rivoplus volumio[3291]: info: ------------------------------ 183ms Sep 08 18:09:08 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 08 18:09:08 rivoplus volumio[3291]: info: MCU Signalled Playback Inactive Sep 08 18:09:08 rivoplus volumio[3291]: info: MCU Signalled Playback Active Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPlay Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::play index 0 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::stop Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::play index undefined Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreStateMachine::startPlaybackTimer Sep 08 18:09:08 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 18:09:08 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 08 18:09:08 rivoplus volumio[3291]: info: [1757347748543] ControllerWebradio::clearAddPlayTrack Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand stop took 6 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 18:09:08 rivoplus volumio[3291]: info: Sep 08 18:09:08 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:08 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:08 rivoplus volumio[3291]: info: sendMpdCommand clear took 3 milliseconds Sep 08 18:09:08 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand load "https://stream.srg-ssr.ch/m/rsp/mp3_128" Sep 08 18:09:08 rivoplus volumio[3291]: info: Sep 08 18:09:08 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:08 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:08 rivoplus volumio[3291]: info: Sep 08 18:09:08 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:08 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:08 rivoplus volumio[3291]: info: Sep 08 18:09:08 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:08 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:08 rivoplus volumio[3291]: error: updateQueue error: null Sep 08 18:09:08 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:08 rivoplus volumio[3291]: info: camilladsp spawned new process with pid 6547, instance 1, run: true Sep 08 18:09:10 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 08 18:09:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 18:09:10 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 08 18:09:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 18:09:10 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 08 18:09:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 18:09:11 rivoplus volumio[3291]: error: updateQueue error: null Sep 08 18:09:11 rivoplus volumio[3291]: error: updateQueue error: null Sep 08 18:09:11 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand add "https://stream.srg-ssr.ch/m/rsp/mp3_128" Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 2732ms Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 2730ms Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 2729ms Sep 08 18:09:11 rivoplus volumio[3291]: info: Sep 08 18:09:11 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:11 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:11 rivoplus volumio[3291]: info: sendMpdCommand add "https://stream.srg-ssr.ch/m/rsp/mp3_128" took 4 milliseconds Sep 08 18:09:11 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 18:09:11 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand play Sep 08 18:09:11 rivoplus volumio[3291]: info: Sep 08 18:09:11 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:11 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:11 rivoplus volumio[3291]: info: Sep 08 18:09:11 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:11 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:11 rivoplus volumio[3291]: info: Sep 08 18:09:11 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:11 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:11 rivoplus volumio[3291]: info: sendMpdCommand play took 9 milliseconds Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 7ms Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 5ms Sep 08 18:09:11 rivoplus volumio[3291]: info: ------------------------------ 4ms Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Sep 08 18:09:12 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate from file: 48000 Sep 08 18:09:12 rivoplus volumio[3291]: info: camilladsp stopping service pid 6547... Sep 08 18:09:12 rivoplus volumio[3291]: info: camilladsp service terminated, instance 1 Sep 08 18:09:12 rivoplus volumio[3291]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 18:09:12 rivoplus volumio[3291]: info: camilladsp service started and running in background, instance 1 Sep 08 18:09:12 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Sep 08 18:09:12 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate from file: 48000 Sep 08 18:09:12 rivoplus volumio[3291]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 73 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 72 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 70 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 68 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 14 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 9 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 9 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand status took 7 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 159ms Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 200ms Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 199ms Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 198ms Sep 08 18:09:12 rivoplus volumio[3291]: error: FusionDsp - WebSocket error: [object Object] Sep 08 18:09:12 rivoplus volumio[3291]: info: camilladsp spawned new process with pid 6561, instance 1, run: true Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 149 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 148 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 149 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 148 milliseconds Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3_128","artist":null,"album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:12 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:12 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:12 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:12 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 435ms Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 434ms Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 433ms Sep 08 18:09:12 rivoplus volumio[3291]: info: ------------------------------ 433ms Sep 08 18:09:12 rivoplus volumio[3291]: info: Sep 08 18:09:12 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 25ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 22 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 22ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 17 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 15ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 14 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 7 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:13 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:13 rivoplus volumio[3291]: info: Sep 08 18:09:13 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 39ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 36 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 29 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 23ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 19 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 17ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 14 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 14ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand status took 10 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 201ms Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 197ms Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 193ms Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 189ms Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 141 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 139 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 140 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 139 milliseconds Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:13 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:13 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LeAnn Rimes - Life Goes On","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:13 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:13 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:13 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:13 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 416ms Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 397ms Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 393ms Sep 08 18:09:13 rivoplus volumio[3291]: info: ------------------------------ 388ms Sep 08 18:09:15 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 08 18:09:15 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 18:09:15 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 08 18:09:15 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 18:09:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 18:09:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 18:09:24 rivoplus volumio[3291]: info: Discovery: Getting this device information Sep 08 18:09:24 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 18:09:25 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 08 18:09:25 rivoplus volumio[3291]: info: [1757347765093] [80s80s] handleBrowseUri curUri: 80s80s Sep 08 18:09:25 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:26 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:26 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:26 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:26 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:26 rivoplus volumio[3291]: info: Sep 08 18:09:26 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand status took 10 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 9ms Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand status took 9 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 8ms Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand status took 6 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 6ms Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand status took 5 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:26 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15859,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:26 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:26 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:26 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15859,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:26 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:26 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:26 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15859,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:26 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:26 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:26 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15859,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Swiss Pop - www.radioswisspop.ch","artist":"Swiss Pop","album":null,"uri":"https://stream.srg-ssr.ch/m/rsp/mp3_128","trackType":""} Sep 08 18:09:26 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:26 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 145ms Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 145ms Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 143ms Sep 08 18:09:26 rivoplus volumio[3291]: info: ------------------------------ 142ms Sep 08 18:09:26 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Sep 08 18:09:26 rivoplus volumio[3291]: info: [1757347766916] [80s80s] handleBrowseUri curUri: 80s80s/nineties Sep 08 18:09:26 rivoplus volumio[3291]: info: [1757347766917] [80s80s] getRadioContent url: nineties Sep 08 18:09:26 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:09:31 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::ClearQueue Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::stop Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::serviceStop Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::serviceStop Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771141] ControllerWebradio::stop Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::clearPlayQueue Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::addQueueItems Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::addQueueItems Sep 08 18:09:31 rivoplus volumio[3291]: info: Preload queue cleared Sep 08 18:09:31 rivoplus volumio[3291]: info: Adding Item to queue: webnineties/7 Sep 08 18:09:31 rivoplus volumio[3291]: info: Exploding uri webnineties/7 in service 80s80s Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771145] [80s80s] explodeUri: webnineties/7 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPlay Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::play index 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::stop Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::play index undefined Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::startPlaybackTimer Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771172] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/hiphop/mp3-192/volumio Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771174] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=265&count=2 Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand stop took 55 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand status took 8 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand status took 7 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand status took 5 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand status took 4 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:31 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 08 18:09:31 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 91ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 89ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 88ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 89ms Sep 08 18:09:31 rivoplus volumio[3291]: info: MCU Signalled Playback Inactive Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771500] [80s80s] received new event containing 2 songs. Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand stop took 2 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand clear took 2 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: error: updateQueue error: null Sep 08 18:09:31 rivoplus volumio[3291]: error: updateQueue error: null Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand consume 1 took 9 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 8ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 6ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 6ms Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771521] [80s80s] adding url: http://streams.90s90s.de/hiphop/mp3-192/volumio Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/hiphop/mp3-192/volumio" Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand add "http://streams.90s90s.de/hiphop/mp3-192/volumio" took 3 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand play Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 12ms Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: Sep 08 18:09:31 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:31 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:31 rivoplus volumio[3291]: info: sendMpdCommand play took 16 milliseconds Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 13ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:31 rivoplus volumio[3291]: info: ------------------------------ 10ms Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771552] [80s80s] Pushing the next song state: Method Man - I'll Be There For You / You're All I Need To Get By (feat. Mary J. Blige) Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music128/v4/32/b0/a6/32b0a6c8-d98b-d688-daef-6ecc70327f32/source/600x600bb.jpg","name":"Method Man - I'll Be There For You / You're All I Need To Get By (feat. Mary J. Blige)","title":"I'll Be There For You / You're All I Need To Get By (feat. Mary J. Blige)","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"283","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 08 18:09:31 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:31 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771559] [80s80s] PlayNextTrack API delay: 30 Sep 08 18:09:31 rivoplus volumio[3291]: info: [1757347771598] [80s80s] Setting timer to: 22441 milliseconds. Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:31 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 1 Sep 08 18:09:31 rivoplus volumio[3291]: info: camilladsp spawned new process with pid 6607, instance 1, run: true Sep 08 18:09:33 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 08 18:09:33 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 08 18:09:34 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 08 18:09:34 rivoplus volumio[3291]: info: camilladsp stopping service pid 6607... Sep 08 18:09:34 rivoplus volumio[3291]: info: camilladsp service terminated, instance 1 Sep 08 18:09:34 rivoplus volumio[3291]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 18:09:34 rivoplus volumio[3291]: info: camilladsp service started and running in background, instance 1 Sep 08 18:09:34 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 08 18:09:34 rivoplus volumio[3291]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 08 18:09:34 rivoplus volumio[3291]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:34 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 75 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 74 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 72 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 72 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: camilladsp spawned new process with pid 6635, instance 1, run: true Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 41 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 44ms Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 41 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 41 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 41 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 41 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 38 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 37 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 36 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 135ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 134ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 132ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 131ms Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:34 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:34 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:34 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:34 rivoplus volumio[3291]: info: Sep 08 18:09:34 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:34 rivoplus volumio[3291]: error: FusionDsp - WebSocket error: [object Object] Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 29 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 19ms Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 13 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 12ms Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 11 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 10ms Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand status took 9 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 94ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 93ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 86ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 90ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 88ms Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:34 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:34 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 45ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 37ms Sep 08 18:09:34 rivoplus volumio[3291]: info: ------------------------------ 34ms Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:35 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:35 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:35 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 08 18:09:35 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 08 18:09:35 rivoplus volumio[3291]: info: Sep 08 18:09:35 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 13ms Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand status took 11 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 11ms Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand status took 9 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 8ms Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand status took 8 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 7ms Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand status took 6 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:35 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:35 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:35 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:35 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:35 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:35 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:35 rivoplus volumio[3291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 28ms Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 25ms Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 23ms Sep 08 18:09:35 rivoplus volumio[3291]: info: ------------------------------ 20ms Sep 08 18:09:42 rivoplus volumio[3291]: info: MCU Signalled Playback Active Sep 08 18:09:42 rivoplus ntpd[4846]: 5.148.175.134 local addr 192.168.178.104 -> Sep 08 18:09:49 rivoplus volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 18:09:49 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 18:09:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 18:09:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 18:09:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 08 18:09:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Sep 08 18:09:50 rivoplus volumio[3291]: info: Received Get System Version Sep 08 18:09:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 18:09:52 rivoplus ntpd[4846]: 185.32.222.237 local addr 192.168.178.104 -> Sep 08 18:09:53 rivoplus ntpd[4846]: 185.17.70.106 local addr 192.168.178.104 -> Sep 08 18:09:54 rivoplus volumio[3291]: info: [1757347794043] [80s80s] Pushing the next song state Method Man - I'll Be There For You / You're All I Need To Get By (feat. Mary J. Blige) and getting next track. Sep 08 18:09:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:54 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 08 18:09:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:54 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music128/v4/32/b0/a6/32b0a6c8-d98b-d688-daef-6ecc70327f32/source/600x600bb.jpg","name":"Method Man - I'll Be There For You / You're All I Need To Get By (feat. Mary J. Blige)","title":"I'll Be There For You / You're All I Need To Get By (feat. Mary J. Blige)","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"283","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 08 18:09:54 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 08 18:09:54 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 08 18:09:54 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 08 18:09:54 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 08 18:09:54 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 08 18:09:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 08 18:09:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 18:09:54 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 08 18:09:54 rivoplus volumio[3291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 18:09:54 rivoplus volumio[3291]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Sep 08 18:09:54 rivoplus volumio[3291]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Sep 08 18:09:54 rivoplus volumio[3291]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Sep 08 18:09:54 rivoplus volumio[3291]: at /volumio/app/index.js:326:81 Sep 08 18:09:54 rivoplus volumio[3291]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Sep 08 18:09:54 rivoplus volumio[3291]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Sep 08 18:09:54 rivoplus volumio[3291]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Sep 08 18:09:54 rivoplus volumio[3291]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:532:22) Sep 08 18:09:54 rivoplus volumio[3291]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:762:12) Sep 08 18:09:54 rivoplus volumio[3291]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Sep 08 18:09:54 rivoplus volumio[3291]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:584:24) Sep 08 18:09:54 rivoplus volumio[3291]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Sep 08 18:09:54 rivoplus volumio[3291]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Sep 08 18:09:54 rivoplus volumio[3291]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Sep 08 18:09:54 rivoplus volumio[3291]: at processImmediate (internal/timers.js:464:21) { Sep 08 18:09:54 rivoplus volumio[3291]: code: 'NumberExpected' Sep 08 18:09:54 rivoplus volumio[3291]: } Sep 08 18:09:54 rivoplus volumio[3291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 18:09:54 rivoplus sudo[6691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 18:08 Sep 08 18:09:54 rivoplus sudo[6691]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"