May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPlay May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::play index 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::stPlaybackTimer May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::updateTrackBlock May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrackBlock May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::serviceStop May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::serviceStop May 16 01:10:37 motivo volumio[1229]: info: [1778893837378] ControllerUPNPBrowser::stop May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand stop May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand stop took 27 milliseconds May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::play index undefined May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::startPlaybackTimer May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: [1778893837406] ControllerUPNPBrowser::clearAddPlayTrack May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand stop May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 2 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand stop took 1 milliseconds May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand clear May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:37 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 7 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand clear took 6 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 6 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 4 milliseconds May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.13:50002/m/NDLNA/5858.dsf" May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: No code May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: 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} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: No code May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: 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} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: No code May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 124ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 119ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 122ms May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:37 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:37 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:37 motivo volumio[1229]: error: updateQueue error: null May 16 01:10:37 motivo volumio[1229]: error: updateQueue error: null May 16 01:10:37 motivo volumio[1229]: error: updateQueue error: null May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 130ms May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.13:50002/m/NDLNA/5858.dsf" May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 9ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 6ms May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: MCU Signalled Playback Inactive May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:37 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand add "http://192.168.3.13:50002/m/NDLNA/5858.dsf" took 72 milliseconds May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand play May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:37 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:37 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 15ms May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand play took 8 milliseconds May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 6ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 6ms May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 27 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 26 milliseconds May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: May 16 01:10:37 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 27 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 14 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 14 milliseconds May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":348,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120,"duration":348,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:37 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 85ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 102ms May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 66 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 65 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand status took 64 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 54 milliseconds May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":348,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:37 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 127ms May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 47 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 48 milliseconds May 16 01:10:37 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 49 milliseconds May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":348,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:37 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":348,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:37 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:37 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:37 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":348,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:37 motivo volumio[1229]: verbose: CURRENT POSITION 1 May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:37 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:37 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:37 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 174ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 171ms May 16 01:10:37 motivo volumio[1229]: info: ------------------------------ 172ms May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:37 motivo volumio[1229]: info: MCU Signalled Playback Active May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPlay May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::play index 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::stop May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::stPlaybackTimer May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::updateTrackBlock May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrackBlock May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::serviceStop May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::serviceStop May 16 01:10:46 motivo volumio[1229]: info: [1778893846274] ControllerUPNPBrowser::stop May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand stop May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand stop took 28 milliseconds May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::play index undefined May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::startPlaybackTimer May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetVisibleSources May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 16 01:10:46 motivo volumio[1229]: info: [1778893846307] ControllerUPNPBrowser::clearAddPlayTrack May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand stop May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 15 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand stop took 9 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 6 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 3 milliseconds May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand clear May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:46 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 9 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand clear took 10 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 10 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 10 milliseconds May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.13:50002/m/NDLNA/5857.dsf" May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5858.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5858.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: No code May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 16 01:10:46 motivo volumio[1229]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 68ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 60ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 57ms May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:46 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:46 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:46 motivo volumio[1229]: error: updateQueue error: null May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 48ms May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: MCU Signalled Playback Inactive May 16 01:10:46 motivo volumio[1229]: error: updateQueue error: null May 16 01:10:46 motivo volumio[1229]: error: updateQueue error: null May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.13:50002/m/NDLNA/5857.dsf" May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 21ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 22ms May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:46 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand add "http://192.168.3.13:50002/m/NDLNA/5857.dsf" took 2 milliseconds May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand play May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:46 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces system playlist update May 16 01:10:46 motivo volumio[1229]: info: Ignoring MPD Status Update May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 5ms May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand play took 5 milliseconds May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 4ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 3ms May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 27 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 26 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 29 milliseconds May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:46 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:46 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 62ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 73ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 72ms May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: May 16 01:10:46 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 48 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 5 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand status took 3 milliseconds May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 30 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 30 milliseconds May 16 01:10:46 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 30 milliseconds May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:46 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:46 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:10:46 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:10:46 motivo volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:10:46 motivo volumio[1229]: verbose: CURRENT POSITION 7 May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState stateService play May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus play May 16 01:10:46 motivo volumio[1229]: info: Received an update from plugin. extracting info from payload May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:10:46 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:10:46 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 130ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 88ms May 16 01:10:46 motivo volumio[1229]: info: ------------------------------ 86ms May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Signalling Playback active due to playback status change May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:10:46 motivo volumio[1229]: info: MCU Signalled Playback Active May 16 01:10:54 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:10:56 motivo volumio[1229]: info: Executing endpoint metavolumio May 16 01:10:56 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 16 01:10:56 motivo volumio[1229]: error: Failed request for metavolumio API May 16 01:10:58 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:10:58 motivo volumio[1229]: info: Preload queue cleared May 16 01:10:59 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:10:59 motivo volumio[1229]: info: Preload queue cleared May 16 01:10:59 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:10:59 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:02 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:11:02 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:06 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:11:06 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:09 motivo ntpd[973]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 16 01:11:10 motivo ntpd[973]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 16 01:11:10 motivo ntpd[973]: DNS: Pool skipping: 160.22.74.161 May 16 01:11:10 motivo ntpd[973]: DNS: Pool skipping: 45.252.250.189 May 16 01:11:10 motivo ntpd[973]: DNS: Pool skipping: 103.221.223.143 May 16 01:11:10 motivo ntpd[973]: DNS: Pool skipping: 115.165.161.155 May 16 01:11:10 motivo ntpd[973]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 16 01:11:10 motivo ntpd[973]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 16 01:11:11 motivo ntpd[973]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 160.22.74.161 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 45.252.250.189 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 103.221.223.143 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 103.184.124.254 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 2401:5b60:0:2::21 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 2400:e920:0:5::14 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 2001:df4:bac0::e35b:f223 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 2400:d760:0:ff09::123 May 16 01:11:11 motivo ntpd[973]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 16 01:11:11 motivo ntpd[973]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 16 01:11:11 motivo ntpd[973]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 103.221.223.143 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 160.22.74.161 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 45.252.250.189 May 16 01:11:11 motivo ntpd[973]: DNS: Pool skipping: 115.165.161.155 May 16 01:11:11 motivo ntpd[973]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 16 01:11:12 motivo ntpd[973]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 16 01:11:13 motivo ntpd[973]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 16 01:11:13 motivo ntpd[973]: DNS: Pool skipping: 160.22.74.161 May 16 01:11:13 motivo ntpd[973]: DNS: Pool skipping: 45.252.250.189 May 16 01:11:13 motivo ntpd[973]: DNS: Pool skipping: 103.221.223.143 May 16 01:11:13 motivo ntpd[973]: DNS: Pool skipping: 115.165.161.155 May 16 01:11:13 motivo ntpd[973]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 16 01:11:25 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:11:26 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:26 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@9295 May 16 01:11:26 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@9295 in service upnp_browser May 16 01:11:28 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioReplaceandPlayItems May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::ClearQueue May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::stPlaybackTimer May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::updateTrackBlock May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrackBlock May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::serviceStop May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::serviceStop May 16 01:11:28 motivo volumio[1229]: info: [1778893888647] ControllerUPNPBrowser::stop May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand stop May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::clearPlayQueue May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::saveQueue May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushQueue May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::addQueueItems May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::addQueueItems May 16 01:11:28 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:28 motivo volumio[1229]: info: Adding Item to queue: upnp/folder/http://192.168.3.13:50001/ContentDirectory/control@22$1034 May 16 01:11:28 motivo volumio[1229]: info: Exploding uri upnp/folder/http://192.168.3.13:50001/ContentDirectory/control@22$1034 in service upnp_browser May 16 01:11:28 motivo volumio[1229]: info: Adding Item to queue: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@9295 May 16 01:11:28 motivo volumio[1229]: info: Using cached record of: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@9295 May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushQueue May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::saveQueue May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::updateTrackBlock May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrackBlock May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 7 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPlay May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::play index 1 May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::play index undefined May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand stop took 54 milliseconds May 16 01:11:28 motivo volumio[1229]: info: May 16 01:11:28 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:11:28 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:11:28 motivo volumio[1229]: info: May 16 01:11:28 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:11:28 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:11:28 motivo volumio[1229]: info: May 16 01:11:28 motivo volumio[1229]: ---------------------------- MPD announces state update: player May 16 01:11:28 motivo volumio[1229]: info: ControllerMpd::getState May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand status May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand status took 6 milliseconds May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand status took 4 milliseconds May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand status took 2 milliseconds May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::parseState May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 01:11:28 motivo volumio[1229]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:11:28 motivo volumio[1229]: verbose: ControllerMpd::parseTrackInfo May 16 01:11:28 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:11:28 motivo volumio[1229]: verbose: CURRENT POSITION 0 May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: No code May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:11:28 motivo volumio[1229]: verbose: CURRENT POSITION 0 May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: No code May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: ControllerMpd::pushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::servicePushState May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5857.dsf","artist":null,"album":null,"uri":"http://192.168.3.13:50002/m/NDLNA/5857.dsf","trackType":"dsf"} May 16 01:11:28 motivo volumio[1229]: verbose: CURRENT POSITION 0 May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::syncState stateService stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::syncState currentStatus stop May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: No code May 16 01:11:28 motivo volumio[1229]: info: CoreStateMachine::pushState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioPushState May 16 01:11:28 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:28 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output update for this device May 16 01:11:28 motivo volumio[1229]: info: MRS: Pushing multiroomSync output May 16 01:11:28 motivo volumio[1229]: info: ------------------------------ 75ms May 16 01:11:28 motivo volumio[1229]: info: ------------------------------ 76ms May 16 01:11:28 motivo volumio[1229]: info: ------------------------------ 75ms May 16 01:11:28 motivo volumio[1229]: info: MCU Signalled Playback Inactive May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:28 motivo volumio[1229]: info: Updating RAAT Signal Path May 16 01:11:33 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:33 motivo volumio[1229]: info: CoreCommandRouter::volumioReplaceandPlayItems May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::ClearQueue May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::stop May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::clearPlayQueue May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::saveQueue May 16 01:11:33 motivo volumio[1229]: info: CoreCommandRouter::volumioPushQueue May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::addQueueItems May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::addQueueItems May 16 01:11:33 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:33 motivo volumio[1229]: info: Adding Item to queue: upnp/folder/http://192.168.3.13:50001/ContentDirectory/control@22$1034 May 16 01:11:33 motivo volumio[1229]: info: Using cached record of: upnp/folder/http://192.168.3.13:50001/ContentDirectory/control@22$1034 May 16 01:11:33 motivo volumio[1229]: info: Adding Item to queue: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@9295 May 16 01:11:33 motivo volumio[1229]: info: Using cached record of: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@9295 May 16 01:11:33 motivo volumio[1229]: info: CoreCommandRouter::volumioPushQueue May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::saveQueue May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::updateTrackBlock May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::getTrackBlock May 16 01:11:33 motivo volumio[1229]: info: CoreCommandRouter::volumioGetState May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::getTrack 0 May 16 01:11:33 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 16 01:11:33 motivo volumio[1229]: info: CoreCommandRouter::volumioPlay May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::play index 1 May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::stop May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::play index undefined May 16 01:11:33 motivo volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 01:11:33 motivo volumio[1229]: info: CorePlayQueue::getTrack 1 May 16 01:11:42 motivo volumio[1229]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 16 01:11:42 motivo volumio[1229]: info: Preload queue cleared May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15534 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15535 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15536 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15537 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15538 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15539 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15540 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15541 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15542 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15543 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15544 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15545 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15546 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15547 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15548 May 16 01:11:42 motivo volumio[1229]: info: Preloading song: upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15549 May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15534 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15535 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15536 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15537 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15538 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15539 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15540 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15541 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15542 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15543 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15544 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: info: Exploding uri upnp/http://192.168.3.13:50001/ContentDirectory/control@22$@15545 in service upnp_browser May 16 01:11:42 motivo volumio[1229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 01:11:42 motivo volumio[1229]: Error: certificate is not yet valid May 16 01:11:42 motivo volumio[1229]: at TLSSocket.onConnectSecure (node:_tls_wrap:1627:34) May 16 01:11:42 motivo volumio[1229]: at TLSSocket.emit (node:events:514:28) May 16 01:11:42 motivo volumio[1229]: at TLSSocket._finishInit (node:_tls_wrap:1038:8) May 16 01:11:42 motivo volumio[1229]: at ssl.onhandshakedone (node:_tls_wrap:824:12) { May 16 01:11:42 motivo volumio[1229]: code: 'CERT_NOT_YET_VALID' May 16 01:11:42 motivo volumio[1229]: } May 16 01:11:42 motivo volumio[1229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 01:11:43 motivo sudo[4114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-16 01:10' May 16 01:11:43 motivo sudo[4114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="e94c5b27ab2607d3918cd6a4973c240c1b2ce37e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 16 00:11:09 UTC 2026" VOLUMIO_VERSION="4.156" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="67ebfb15187d4de64266b21b13da5443"