-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-11-03 10:10:16 CET. -- Nov 03 10:09:14 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 03 10:09:14 motivo volumio[12832]: info: TuneIn handleBrowseUri: tunein/best Nov 03 10:09:15 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:35 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:35 motivo volumio[12832]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 10:09:35 motivo volumio[12832]: info: CoreStateMachine::ClearQueue Nov 03 10:09:35 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:35 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:35 motivo volumio[12832]: info: CorePlayQueue::clearPlayQueue Nov 03 10:09:35 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:35 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:35 motivo volumio[12832]: info: CoreStateMachine::addQueueItems Nov 03 10:09:35 motivo volumio[12832]: info: CorePlayQueue::addQueueItems Nov 03 10:09:35 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:35 motivo volumio[12832]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s63852 Nov 03 10:09:35 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::updateTrackBlock Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::getTrackBlock Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioPlay Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::play index 0 Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::play index undefined Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::startPlaybackTimer Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 10:09:36 motivo volumio[12832]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Nov 03 10:09:36 motivo volumio[12832]: info: [1762160976056] ControllerWebradio::clearAddPlayTrack Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 10:09:36 motivo volumio[12832]: info: sendMpdCommand stop took 8 milliseconds Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: info: sendMpdCommand clear took 4 milliseconds Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:36 motivo volumio[12832]: info: ------------------------------ 10ms Nov 03 10:09:36 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::ClearQueue Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::clearPlayQueue Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::addQueueItems Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::addQueueItems Nov 03 10:09:36 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:36 motivo volumio[12832]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s63852 Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::updateTrackBlock Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::getTrackBlock Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioPlay Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::play index 0 Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::play index undefined Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::startPlaybackTimer Nov 03 10:09:36 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 10:09:36 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 10:09:36 motivo volumio[12832]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Nov 03 10:09:36 motivo volumio[12832]: info: [1762160976662] ControllerWebradio::clearAddPlayTrack Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 10:09:36 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:36 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:36 motivo volumio[12832]: info: ------------------------------ 755ms Nov 03 10:09:36 motivo volumio[12832]: info: ------------------------------ 754ms Nov 03 10:09:36 motivo volumio[12832]: info: sendMpdCommand stop took 166 milliseconds Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: info: sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" took 8 milliseconds Nov 03 10:09:36 motivo volumio[12832]: info: sendMpdCommand clear took 5 milliseconds Nov 03 10:09:36 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand play Nov 03 10:09:36 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: info: Nov 03 10:09:36 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:36 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:36 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:36 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:36 motivo volumio[12832]: info: ------------------------------ 14ms Nov 03 10:09:36 motivo volumio[12832]: info: ------------------------------ 13ms Nov 03 10:09:36 motivo volumio[12832]: info: sendMpdCommand play took 9 milliseconds Nov 03 10:09:37 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:37 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:37 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 704ms Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 702ms Nov 03 10:09:37 motivo volumio[12832]: info: Nov 03 10:09:37 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:37 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:37 motivo volumio[12832]: info: Nov 03 10:09:37 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:37 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:37 motivo volumio[12832]: info: Nov 03 10:09:37 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:37 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:37 motivo volumio[12832]: info: sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" took 15 milliseconds Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 14ms Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 7ms Nov 03 10:09:37 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 10:09:37 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand play Nov 03 10:09:37 motivo volumio[12832]: info: Nov 03 10:09:37 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:37 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:37 motivo volumio[12832]: info: Nov 03 10:09:37 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:37 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 13ms Nov 03 10:09:37 motivo volumio[12832]: info: sendMpdCommand play took 7 milliseconds Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 5ms Nov 03 10:09:37 motivo volumio[12832]: info: ------------------------------ 4ms Nov 03 10:09:38 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::ClearQueue Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::clearPlayQueue Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::addQueueItems Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::addQueueItems Nov 03 10:09:38 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:38 motivo volumio[12832]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s63852 Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::updateTrackBlock Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::getTrackBlock Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::volumioPlay Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::play index 0 Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::play index undefined Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:38 motivo volumio[12832]: info: CoreStateMachine::startPlaybackTimer Nov 03 10:09:38 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 10:09:38 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 10:09:38 motivo volumio[12832]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Nov 03 10:09:38 motivo volumio[12832]: info: [1762160978220] ControllerWebradio::clearAddPlayTrack Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 10:09:38 motivo volumio[12832]: info: Nov 03 10:09:38 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:38 motivo volumio[12832]: info: sendMpdCommand stop took 5 milliseconds Nov 03 10:09:38 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 10:09:38 motivo volumio[12832]: info: Nov 03 10:09:38 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:38 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:38 motivo volumio[12832]: info: sendMpdCommand status took 3 milliseconds Nov 03 10:09:38 motivo volumio[12832]: info: sendMpdCommand clear took 4 milliseconds Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:38 motivo volumio[12832]: info: Nov 03 10:09:38 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:38 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:38 motivo volumio[12832]: info: Nov 03 10:09:38 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:38 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:38 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:38 motivo volumio[12832]: info: ------------------------------ 10ms Nov 03 10:09:38 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 03 10:09:38 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:38 motivo volumio[12832]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 03 10:09:38 motivo volumio[12832]: info: ------------------------------ 18ms Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:39 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand status took 823 milliseconds Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand status took 821 milliseconds Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" took 5 milliseconds Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand play Nov 03 10:09:39 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:39 motivo volumio[12832]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 03 10:09:39 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::syncState stateService stop Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus stop Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:39 motivo volumio[12832]: info: No code Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:39 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:39 motivo volumio[12832]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 03 10:09:39 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::syncState stateService stop Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus stop Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:39 motivo volumio[12832]: info: No code Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:39 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 899ms Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 900ms Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:39 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:39 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 86ms Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand play took 81 milliseconds Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 5ms Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 3ms Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:39 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:39 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:39 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:39 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:39 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:39 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:39 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:39 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:39 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:39 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:39 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:39 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:39 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::ClearQueue Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::clearPlayQueue Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::addQueueItems Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::addQueueItems Nov 03 10:09:39 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:39 motivo volumio[12832]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s63852 Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::updateTrackBlock Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::getTrackBlock Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioPlay Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::play index 0 Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::play index undefined Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:39 motivo volumio[12832]: info: CoreStateMachine::startPlaybackTimer Nov 03 10:09:39 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 10:09:39 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 10:09:39 motivo volumio[12832]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Nov 03 10:09:39 motivo volumio[12832]: info: [1762160979216] ControllerWebradio::clearAddPlayTrack Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 72ms Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 72ms Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand stop took 3 milliseconds Nov 03 10:09:39 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:39 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand status took 4 milliseconds Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand clear took 4 milliseconds Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:39 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:39 motivo volumio[12832]: info: Nov 03 10:09:39 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:39 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:39 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 6ms Nov 03 10:09:39 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 03 10:09:39 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:39 motivo volumio[12832]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 03 10:09:39 motivo volumio[12832]: info: ------------------------------ 13ms Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:40 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 814 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 813 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" took 4 milliseconds Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand play Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 03 10:09:40 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState stateService stop Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus stop Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: No code Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 03 10:09:40 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState stateService stop Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus stop Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: No code Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 886ms Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 886ms Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:40 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:40 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 79ms Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand play took 75 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 5ms Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 3ms Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:40 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:40 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:40 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:40 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:40 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:40 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:40 motivo volumio[12832]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Nov 03 10:09:40 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:40 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:40 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:40 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:40 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:40 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 37ms Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 37ms Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 11 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 14 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 11 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 5 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:40 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus stop Nov 03 10:09:40 motivo volumio[12832]: info: ------------------------------ 28ms Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:40 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:40 motivo volumio[12832]: info: Nov 03 10:09:40 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 8 milliseconds Nov 03 10:09:40 motivo volumio[12832]: info: sendMpdCommand status took 5 milliseconds Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:40 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:40 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:40 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:40 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:40 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:40 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:40 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:40 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:40 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:40 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 121ms Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 120ms Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 114ms Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:41 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:41 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 86ms Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand status took 84 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 80 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 79 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 7ms Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand status took 6 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 5ms Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand status took 4 milliseconds Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"r101_mp3","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:41 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:41 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"r101_mp3","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:41 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:41 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 157ms Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 145ms Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 119 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 118 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 118 milliseconds Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"r101_mp3","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:41 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:41 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"r101_mp3","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:41 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:41 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1360,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"r101_mp3","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:41 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:41 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 275ms Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 194ms Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 194ms Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::ClearQueue Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::stPlaybackTimer Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::updateTrackBlock Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrackBlock Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::serviceStop Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::serviceStop Nov 03 10:09:41 motivo volumio[12832]: info: [1762160981298] ControllerWebradio::stop Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::clearPlayQueue Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::addQueueItems Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::addQueueItems Nov 03 10:09:41 motivo volumio[12832]: info: Preload queue cleared Nov 03 10:09:41 motivo volumio[12832]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s63852 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushQueue Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::saveQueue Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::updateTrackBlock Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrackBlock Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPlay Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::play index 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::stop Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::play index undefined Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::startPlaybackTimer Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 10:09:41 motivo volumio[12832]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Nov 03 10:09:41 motivo volumio[12832]: info: [1762160981322] ControllerWebradio::clearAddPlayTrack Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand stop took 36 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand stop took 14 milliseconds Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:41 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand status took 8 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand clear took 6 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand status took 4 milliseconds Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand status took 2 milliseconds Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 03 10:09:41 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:41 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:41 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:41 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:41 motivo volumio[12832]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 36ms Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 34ms Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:41 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:41 motivo volumio[12832]: info: Nov 03 10:09:41 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:41 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:41 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 38ms Nov 03 10:09:41 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 03 10:09:41 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:41 motivo volumio[12832]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 03 10:09:41 motivo volumio[12832]: info: ------------------------------ 48ms Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:41 motivo volumio[12832]: info: MCU Signalled Playback Active Nov 03 10:09:41 motivo volumio[12832]: info: MCU Signalled Playback Inactive Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" Nov 03 10:09:42 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:42 motivo volumio[12832]: error: updateQueue error: null Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 868ms Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 866ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand add "http://icecast.unitedradio.it/r101_mp3" took 3 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand play Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 7ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand play took 6 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 9ms Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 7ms Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 15 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 15 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 4 milliseconds Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 11 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 5 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 4 milliseconds Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus stop Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: 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":"r101_mp3","artist":null,"album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 61ms Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 86ms Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 76ms Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 62 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 61 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 62 milliseconds Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: 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":"r101_mp3","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: 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":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: 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":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 137ms Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 130ms Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 129ms Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 115ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 113 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 115ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 112 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 52ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 52 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 51ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 50 milliseconds Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces system playlist update Nov 03 10:09:42 motivo volumio[12832]: info: Ignoring MPD Status Update Nov 03 10:09:42 motivo volumio[12832]: info: Nov 03 10:09:42 motivo volumio[12832]: ---------------------------- MPD announces state update: player Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::getState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand status Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 6ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 6 milliseconds Nov 03 10:09:42 motivo volumio[12832]: info: ------------------------------ 5ms Nov 03 10:09:42 motivo volumio[12832]: info: sendMpdCommand status took 4 milliseconds Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::parseState Nov 03 10:09:42 motivo volumio[12832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1357,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1357,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1357,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:42 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:42 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1357,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:42 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:42 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:42 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:42 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:42 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:43 motivo volumio[12832]: info: ------------------------------ 196ms Nov 03 10:09:43 motivo volumio[12832]: info: ------------------------------ 196ms Nov 03 10:09:43 motivo volumio[12832]: info: ------------------------------ 135ms Nov 03 10:09:43 motivo volumio[12832]: info: ------------------------------ 134ms Nov 03 10:09:43 motivo volumio[12832]: info: sendMpdCommand playlistinfo took 76 milliseconds Nov 03 10:09:43 motivo volumio[12832]: verbose: ControllerMpd::parseTrackInfo Nov 03 10:09:43 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:43 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:43 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:43 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:43 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:43 motivo volumio[12832]: info: ControllerMpd::pushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::servicePushState Nov 03 10:09:43 motivo volumio[12832]: info: CorePlayQueue::getTrack 0 Nov 03 10:09:43 motivo volumio[12832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DIODATO~FAI RUMORE~~0","artist":"FM_101_mp3_radiomediaset","album":null,"uri":"http://icecast.unitedradio.it/r101_mp3","trackType":""} Nov 03 10:09:43 motivo volumio[12832]: verbose: CURRENT POSITION 0 Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::syncState stateService play Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::syncState currentStatus play Nov 03 10:09:43 motivo volumio[12832]: info: Received an update from plugin. extracting info from payload Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:43 motivo volumio[12832]: info: CoreStateMachine::pushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioPushState Nov 03 10:09:43 motivo volumio[12832]: info: CoreCommandRouter::volumioGetState Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output update for this device Nov 03 10:09:43 motivo volumio[12832]: info: MRS: Pushing multiroomSync output Nov 03 10:09:43 motivo volumio[12832]: info: ------------------------------ 122ms Nov 03 10:09:43 motivo volumio[12832]: info: ------------------------------ 126ms Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Signalling Playback active due to playback status change Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: Updating RAAT Signal Path Nov 03 10:09:43 motivo volumio[12832]: info: MCU Signalled Playback Active Nov 03 10:10:02 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Nov 03 10:10:02 motivo volumio[12832]: info: MRS: Starting sonos device: [object Object] Nov 03 10:10:02 motivo volumio[12832]: info: Enabled audio output: RINCON_5CAAFDFDB7D601400:2875073524 Nov 03 10:10:02 motivo volumio[12832]: info: MRS: Setting this device as Streaming Server Nov 03 10:10:02 motivo volumio[12832]: info: Nov 03 10:10:02 motivo volumio[12832]: [1762161002066] ---------------------------- MRS: Setting Streaming Server Nov 03 10:10:02 motivo volumio[12832]: info: MRS: enable multiroom server output Nov 03 10:10:02 motivo volumio[12832]: info: MRS: Set multiroom target PCM to volumioMultiRoom Nov 03 10:10:02 motivo volumio[12832]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Nov 03 10:10:02 motivo volumio[12832]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 03 10:10:02 motivo volumio[12832]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Nov 03 10:10:02 motivo volumio[12832]: info: MRS: STARTING volumioStreaming Nov 03 10:10:02 motivo sudo[13459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 03 10:10:02 motivo sudo[13459]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:02 motivo sudo[13459]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:02 motivo sudo[13462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Nov 03 10:10:02 motivo sudo[13462]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:02 motivo systemd[1]: Started VolumioStreamingService. Nov 03 10:10:02 motivo sudo[13462]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:02 motivo sudo[13465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 03 10:10:02 motivo volumio[12832]: info: MRS: volumioStreaming STARTED Nov 03 10:10:02 motivo sudo[13465]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:02 motivo sudo[13465]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:06 motivo volumio[12832]: info: MRS: Starting sonos device: uuid:RINCON_5CAAFDFDB7D601400 Nov 03 10:10:06 motivo volumio[12832]: info: Refreshing Cached IP Addresses Nov 03 10:10:06 motivo sudo[13474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 10:10:06 motivo sudo[13474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:06 motivo sudo[13477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 10:10:06 motivo sudo[13474]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:06 motivo sudo[13477]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:06 motivo sudo[13477]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:06 motivo volumio[12832]: info: MRS: Started sonos device: uuid:RINCON_5CAAFDFDB7D601400 Nov 03 10:10:14 motivo volumio[12832]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Nov 03 10:10:14 motivo volumio[12832]: info: MRS: Starting cast device: LS60-Wireless-7b3846501cc750d90b6396d8c0d59903 Nov 03 10:10:14 motivo volumio[12832]: info: Enabled audio output: LS60-Wireless-7b3846501cc750d90b6396d8c0d59903 Nov 03 10:10:14 motivo volumio[12832]: info: MRS: Setting this device as Streaming Server Nov 03 10:10:14 motivo volumio[12832]: info: Nov 03 10:10:14 motivo volumio[12832]: [1762161014483] ---------------------------- MRS: Setting Streaming Server Nov 03 10:10:14 motivo volumio[12832]: info: MRS: enable multiroom server output Nov 03 10:10:14 motivo volumio[12832]: info: MRS: Set multiroom target PCM to volumioMultiRoom Nov 03 10:10:14 motivo volumio[12832]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Nov 03 10:10:14 motivo volumio[12832]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Nov 03 10:10:14 motivo volumio[12832]: info: MRS: Stopping sonos device: uuid:RINCON_5CAAFDFDB7D601400 Nov 03 10:10:14 motivo volumio[12832]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Nov 03 10:10:14 motivo volumio[12832]: info: MRS: STARTING volumioStreaming Nov 03 10:10:14 motivo sudo[13540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts Nov 03 10:10:14 motivo sudo[13540]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:14 motivo sudo[13540]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:14 motivo sudo[13543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Nov 03 10:10:14 motivo sudo[13543]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:14 motivo systemd[1]: Stopping VolumioStreamingService... Nov 03 10:10:14 motivo systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Nov 03 10:10:14 motivo systemd[1]: volumioStreaming.service: Succeeded. Nov 03 10:10:14 motivo volumio[12832]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 10:10:14 motivo systemd[1]: Stopped VolumioStreamingService. Nov 03 10:10:14 motivo volumio[12832]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 10:10:14 motivo systemd[1]: Started VolumioStreamingService. Nov 03 10:10:14 motivo sudo[13543]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:14 motivo sudo[13548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream12.ts Nov 03 10:10:14 motivo sudo[13548]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 10:10:14 motivo sudo[13548]: pam_unix(sudo:session): session closed for user root Nov 03 10:10:14 motivo volumio[12832]: info: MRS: volumioStreaming STARTED Nov 03 10:10:14 motivo volumio[12832]: info: MRS: Connected to cast device: LS60-Wireless-7b3846501cc750d90b6396d8c0d59903 Nov 03 10:10:15 motivo volumio[12832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 10:10:15 motivo volumio[12832]: TypeError: Cannot read property 'on' of undefined Nov 03 10:10:15 motivo volumio[12832]: at /volumio/app/plugins/audio_interface/multiroom/multiroom_real:1:29834 Nov 03 10:10:15 motivo volumio[12832]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Nov 03 10:10:15 motivo volumio[12832]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Nov 03 10:10:15 motivo volumio[12832]: at fn.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Nov 03 10:10:15 motivo volumio[12832]: at fn.emit (events.js:327:22) Nov 03 10:10:15 motivo volumio[12832]: at Channel.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Nov 03 10:10:15 motivo volumio[12832]: at Channel.emit (events.js:315:20) Nov 03 10:10:15 motivo volumio[12832]: at Client.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Nov 03 10:10:15 motivo volumio[12832]: at Client.emit (events.js:327:22) Nov 03 10:10:15 motivo volumio[12832]: at PacketStreamWrapper.onpacket (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Nov 03 10:10:15 motivo volumio[12832]: at PacketStreamWrapper.emit (events.js:315:20) Nov 03 10:10:15 motivo volumio[12832]: at TLSSocket. (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Nov 03 10:10:15 motivo volumio[12832]: at TLSSocket.emit (events.js:315:20) Nov 03 10:10:15 motivo volumio[12832]: at emitReadable_ (internal/streams/readable.js:569:12) Nov 03 10:10:15 motivo volumio[12832]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Nov 03 10:10:15 motivo volumio[12832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 10:10:16 motivo sudo[13564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 10:09 Nov 03 10:10:16 motivo sudo[13564]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 12:42:34 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="03b8c851b751de445da30842a70a04d1"