-- Logs begin at Thu 2025-10-30 19:33:59 MSK, end at Fri 2025-10-31 19:05:00 MSK. -- Oct 31 19:04:05 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 31 19:04:05 smsl-n100 volumio[870]: info: CURURI: albums://Melody%20Gardot/Worrisome%20Heart Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/02 - All That I Need Is Love.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/03 - Gone.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/04 - Sweet Memory.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/05 - Some Lessons.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/06 - Quiet Fire.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/08 - Love Me Like a River Does.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/09 - Goodnite.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Preloading song: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/10 - Twilight.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F01%20-%20Worrisome%20Heart.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Executing endpoint getSimilarAlbums Oct 31 19:04:05 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/02 - All That I Need Is Love.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/03 - Gone.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F02%20-%20All%20That%20I%20Need%20Is%20Love.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/02 - All That I Need Is Love.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F03%20-%20Gone.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/03 - Gone.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Executing endpoint metavolumio Oct 31 19:04:05 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 19:04:05 smsl-n100 volumio[870]: info: Executing endpoint metavolumio Oct 31 19:04:05 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/04 - Sweet Memory.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F04%20-%20Sweet%20Memory.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/04 - Sweet Memory.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/05 - Some Lessons.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F05%20-%20Some%20Lessons.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/05 - Some Lessons.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/06 - Quiet Fire.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F06%20-%20Quiet%20Fire.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/06 - Quiet Fire.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F07%20-%20One%20Day.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/08 - Love Me Like a River Does.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F08%20-%20Love%20Me%20Like%20a%20River%20Does.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/08 - Love Me Like a River Does.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/09 - Goodnite.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F09%20-%20Goodnite.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/09 - Goodnite.flac Oct 31 19:04:05 smsl-n100 volumio[870]: info: Exploding uri music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/10 - Twilight.flac in service mpd Oct 31 19:04:05 smsl-n100 volumio[870]: info: ALBUMART /albumart?cacheid=449&web=Melody%20Gardot/Worrisome%20Heart/extralarge&path=%2Fmnt%2FUSB%2FIAFF%2Fdownload%2FMelody%20Gardot%2FMelody%20Gardot%20-%20Worrisome%20Heart%20(2007)%2F10%20-%20Twilight.flac&metadata=false Oct 31 19:04:05 smsl-n100 volumio[870]: info: URI /mnt/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/10 - Twilight.flac Oct 31 19:04:10 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::ClearQueue Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::stPlaybackTimer Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::updateTrackBlock Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrackBlock Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 4 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::serviceStop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 4 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::serviceStop Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::stop Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::clearPlayQueue Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::saveQueue Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushQueue Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::addQueueItems Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::addQueueItems Oct 31 19:04:10 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:10 smsl-n100 volumio[870]: info: Adding Item to queue: albums://Melody%20Gardot/Worrisome%20Heart Oct 31 19:04:10 smsl-n100 volumio[870]: info: Exploding uri albums://Melody%20Gardot/Worrisome%20Heart in service mpd Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand stop took 62 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushQueue Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::saveQueue Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::updateTrackBlock Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrackBlock Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPlay Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::play index 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::play index undefined Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::startPlaybackTimer Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::clearAddPlayTracks USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 48 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand stop took 11 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand clear took 20 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 15 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 10 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand add "USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac" Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: 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":"Rollercoaster","artist":"Janet Jackson","album":"Discipline (Deluxe Edition)","uri":"USB/IAFF/download/Janet Jackson [FLAC]/Janet Jackson - 2023 - Discipline (Deluxe Edition)/05 - Rollercoaster.flac","trackType":"flac"} Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: No code Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: 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} Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: No code Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: 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} Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: No code Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 324ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 266ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 263ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:10 smsl-n100 volumio[870]: error: updateQueue error: null Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 284ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand add "USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac" took 276 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 25ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 21ms Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand play Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: 100, message: undefined Oct 31 19:04:10 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 31 19:04:10 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: 100, message: undefined Oct 31 19:04:10 smsl-n100 volumio[870]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 262ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand play took 255 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 141ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: ------------------------------ 137ms Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: Oct 31 19:04:10 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 47 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 44 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 29 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand status took 26 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 82 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 81 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 81 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"Worrisome Heart","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac","trackType":"flac"} Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:10 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"Worrisome Heart","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac","trackType":"flac"} Oct 31 19:04:10 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:10 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:10 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:10 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:11 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"Worrisome Heart","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac","trackType":"flac"} Oct 31 19:04:11 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:11 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:11 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"608 Kbps","isStreaming":false,"title":"Worrisome Heart","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac","trackType":"flac"} Oct 31 19:04:11 smsl-n100 volumio[870]: verbose: CURRENT POSITION 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:11 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:11 smsl-n100 volumio[870]: info: ------------------------------ 289ms Oct 31 19:04:11 smsl-n100 volumio[870]: info: ------------------------------ 372ms Oct 31 19:04:11 smsl-n100 volumio[870]: info: ------------------------------ 351ms Oct 31 19:04:11 smsl-n100 volumio[870]: info: ------------------------------ 348ms Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:11 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:11 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:11 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: 100, message: undefined Oct 31 19:04:11 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 31 19:04:11 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: 100, message: undefined Oct 31 19:04:11 smsl-n100 volumio[870]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 31 19:04:26 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::ClearQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::stPlaybackTimer Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::updateTrackBlock Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrackBlock Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::serviceStop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 0 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::serviceStop Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::stop Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::clearPlayQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::saveQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::addQueueItems Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::addQueueItems Oct 31 19:04:26 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/01 - Worrisome Heart.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/02 - All That I Need Is Love.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/02 - All That I Need Is Love.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/03 - Gone.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/03 - Gone.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/04 - Sweet Memory.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/04 - Sweet Memory.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/05 - Some Lessons.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/05 - Some Lessons.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/06 - Quiet Fire.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/06 - Quiet Fire.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::saveQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::updateTrackBlock Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrackBlock Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPlay Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::play index 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::addQueueItems Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::addQueueItems Oct 31 19:04:26 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/08 - Love Me Like a River Does.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/08 - Love Me Like a River Does.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/09 - Goodnite.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/09 - Goodnite.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Adding Item to queue: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/10 - Twilight.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: Using cached record of: music-library/USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/10 - Twilight.flac Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::saveQueue Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::play index undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::updateTrackBlock Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrackBlock Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::startPlaybackTimer Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::clearAddPlayTracks USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand stop took 168 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand stop took 28 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand clear Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 27 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand clear took 26 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 21 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 16 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand add "USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac" Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: 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} Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: No code Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: 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} Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: No code Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 187ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 185ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:26 smsl-n100 volumio[870]: error: updateQueue error: null Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 193ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 186 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand add "USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac" took 185 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 20ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 17ms Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand play Oct 31 19:04:26 smsl-n100 volumio[870]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 232ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces system playlist update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Ignoring MPD Status Update Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 196ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand play took 187 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 181ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: ------------------------------ 177ms Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:26 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:26 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:26 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: 100, message: undefined Oct 31 19:04:26 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 31 19:04:26 smsl-n100 volumio[870]: error: Serial API: Failed to decode command: 100, message: undefined Oct 31 19:04:26 smsl-n100 volumio[870]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 97 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 92 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 86 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: Oct 31 19:04:26 smsl-n100 volumio[870]: ---------------------------- MPD announces state update: player Oct 31 19:04:26 smsl-n100 volumio[870]: info: ControllerMpd::getState Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand status Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 125 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 117 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 116 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 114 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 110 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: info: sendMpdCommand status took 106 milliseconds Oct 31 19:04:26 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseState Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 19:04:27 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"One Day","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac","trackType":"flac"} Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus stop Oct 31 19:04:27 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"One Day","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac","trackType":"flac"} Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:27 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"One Day","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac","trackType":"flac"} Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:27 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: ------------------------------ 323ms Oct 31 19:04:27 smsl-n100 volumio[870]: info: ------------------------------ 372ms Oct 31 19:04:27 smsl-n100 volumio[870]: info: ------------------------------ 369ms Oct 31 19:04:27 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 176 milliseconds Oct 31 19:04:27 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 171 milliseconds Oct 31 19:04:27 smsl-n100 volumio[870]: info: sendMpdCommand playlistinfo took 170 milliseconds Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: ControllerMpd::parseTrackInfo Oct 31 19:04:27 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"One Day","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac","trackType":"flac"} Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:27 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"One Day","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac","trackType":"flac"} Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:27 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: ControllerMpd::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::servicePushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"One Day","artist":"Melody Gardot","album":"Worrisome Heart","uri":"USB/IAFF/download/Melody Gardot/Melody Gardot - Worrisome Heart (2007)/07 - One Day.flac","trackType":"flac"} Oct 31 19:04:27 smsl-n100 volumio[870]: verbose: CURRENT POSITION 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState stateService play Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::syncState currentStatus play Oct 31 19:04:27 smsl-n100 volumio[870]: info: Received an update from plugin. extracting info from payload Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreStateMachine::pushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:27 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioPushState Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output update for this device Oct 31 19:04:27 smsl-n100 volumio[870]: info: MRS: Pushing multiroomSync output Oct 31 19:04:27 smsl-n100 volumio[870]: info: ------------------------------ 503ms Oct 31 19:04:27 smsl-n100 volumio[870]: info: ------------------------------ 491ms Oct 31 19:04:27 smsl-n100 volumio[870]: info: ------------------------------ 489ms Oct 31 19:04:29 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 19:04:29 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 19:04:29 smsl-n100 volumio[870]: info: Discovery: Getting this device information Oct 31 19:04:29 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioGetState Oct 31 19:04:29 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:29 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 19:04:43 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:43 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:44 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:44 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:46 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:46 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:50 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:50 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:51 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:51 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:58 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:58 smsl-n100 volumio[870]: info: Preload queue cleared Oct 31 19:04:59 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 19:04:59 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 19:04:59 smsl-n100 volumio[870]: info: Discovery: Getting this device information Oct 31 19:04:59 smsl-n100 volumio[870]: info: CoreCommandRouter::volumioGetState Oct 31 19:04:59 smsl-n100 volumio[870]: info: CorePlayQueue::getTrack 6 Oct 31 19:04:59 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 19:04:59 smsl-n100 volumio[870]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 19:04:59 smsl-n100 volumio[870]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 19:04:59 smsl-n100 volumio[870]: TypeError: Cannot read property 'length' of undefined Oct 31 19:04:59 smsl-n100 volumio[870]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 31 19:04:59 smsl-n100 volumio[870]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 31 19:04:59 smsl-n100 volumio[870]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 31 19:04:59 smsl-n100 volumio[870]: at Parser.emit (events.js:400:28) Oct 31 19:04:59 smsl-n100 volumio[870]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 31 19:04:59 smsl-n100 volumio[870]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 31 19:04:59 smsl-n100 volumio[870]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 31 19:04:59 smsl-n100 volumio[870]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 31 19:04:59 smsl-n100 volumio[870]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 31 19:04:59 smsl-n100 volumio[870]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 31 19:04:59 smsl-n100 volumio[870]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 31 19:04:59 smsl-n100 volumio[870]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 31 19:04:59 smsl-n100 volumio[870]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 31 19:04:59 smsl-n100 volumio[870]: at IncomingMessage.emit (events.js:412:35) Oct 31 19:04:59 smsl-n100 volumio[870]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 31 19:04:59 smsl-n100 volumio[870]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 31 19:04:59 smsl-n100 volumio[870]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 19:05:00 smsl-n100 sudo[3415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-31 19:04 Oct 31 19:05:00 smsl-n100 sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"