Oct 13 22:22:01 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 22:22:05 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 22:22:09 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 22:22:13 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 22:22:17 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 22:22:20 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 22:22:22 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 22:22:22 volumio volumio[1056]: info: CURURI: music-library Oct 13 22:22:22 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:22 volumio volumio[1056]: info: CoreCommandRouter::volumioGetQueue Oct 13 22:22:22 volumio volumio[1056]: info: CoreStateMachine::getQueue Oct 13 22:22:22 volumio volumio[1056]: info: CorePlayQueue::getQueue Oct 13 22:22:25 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 22:22:25 volumio volumio[1056]: info: CURURI: music-library/NAS Oct 13 22:22:25 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:27 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 22:22:27 volumio volumio[1056]: info: CURURI: music-library/NAS/Jozene Oct 13 22:22:27 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:32 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 1 Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 1 Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:22:32 volumio volumio[1056]: info: [1791922952480] ControllerUPNPBrowser::stop Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:22:32 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:32 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Akos - A het parancsszo Oct 13 22:22:32 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Akos - A het parancsszo in service mpd Oct 13 22:22:32 volumio volumio[1056]: info: Oct 13 22:22:32 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:32 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=%C3%81kos/A%20h%C3%A9t%20parancssz%C3%B3/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAkos%20-%20A%20het%20parancsszo%2F01%20-%20Az%20%C3%A9j%20fel%C3%A9n%20t%C3%BAl%20(r%C3%A9szlet)(nyit%C3%A1ny).flac&metadata=false Oct 13 22:22:32 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac Oct 13 22:22:32 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=%C3%81kos/A%20h%C3%A9t%20parancssz%C3%B3/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAkos%20-%20A%20het%20parancsszo%2F02%20-%20Pygmalyon%20dala.flac&metadata=false Oct 13 22:22:32 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Akos - A het parancsszo/02 - Pygmalyon dala.flac Oct 13 22:22:32 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=%C3%81kos/A%20h%C3%A9t%20parancssz%C3%B3/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAkos%20-%20A%20het%20parancsszo%2F04%20-%20Am%C3%ADg%20megteheted.flac&metadata=false Oct 13 22:22:32 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Akos - A het parancsszo/04 - Amíg megteheted.flac Oct 13 22:22:32 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=%C3%81kos/A%20h%C3%A9t%20parancssz%C3%B3/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAkos%20-%20A%20het%20parancsszo%2F05%20-%20F%C3%A9nyk%C3%A9p.flac&metadata=false Oct 13 22:22:32 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Akos - A het parancsszo/05 - Fénykép.flac Oct 13 22:22:32 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=%C3%81kos/A%20h%C3%A9t%20parancssz%C3%B3/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAkos%20-%20A%20het%20parancsszo%2F06%20-%20K%C3%A9jverseny%20(dal%20egym%C3%A1snak).flac&metadata=false Oct 13 22:22:32 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Akos - A het parancsszo/06 - Kéjverseny (dal egymásnak).flac Oct 13 22:22:32 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=%C3%81kos/A%20h%C3%A9t%20parancssz%C3%B3/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAkos%20-%20A%20het%20parancsszo%2F07%20-%20A%20H%C3%A9t%20Parancssz%C3%B3%20(A%20F%C3%A9nybe).flac&metadata=false Oct 13 22:22:32 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Akos - A het parancsszo/07 - A Hét Parancsszó (A Fénybe).flac Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand stop took 20 milliseconds Oct 13 22:22:32 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 1 Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand stop took 0 milliseconds Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:22:32 volumio volumio[1056]: info: Oct 13 22:22:32 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:32 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand clear took 1 milliseconds Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac" Oct 13 22:22:32 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:32 volumio volumio[1056]: 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":"15801.flac","artist":null,"album":null,"uri":"http://192.168.0.202:8200/MediaItems/15801.flac","trackType":"flac"} Oct 13 22:22:32 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:32 volumio volumio[1056]: info: No code Oct 13 22:22:32 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:32 volumio volumio[1056]: info: ------------------------------ 6ms Oct 13 22:22:32 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:22:32 volumio volumio[1056]: info: Oct 13 22:22:32 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:32 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:32 volumio volumio[1056]: info: ------------------------------ 4ms Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac" took 4 milliseconds Oct 13 22:22:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:22:32 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:22:32 volumio volumio[1056]: info: sendMpdCommand play took 3 milliseconds Oct 13 22:22:33 volumio volumio[1056]: info: Oct 13 22:22:33 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:33 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:33 volumio volumio[1056]: info: Oct 13 22:22:33 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:33 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:33 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:33 volumio volumio[1056]: info: sendMpdCommand status took 15 milliseconds Oct 13 22:22:33 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:33 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:33 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:33 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2098,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"187 Kbps","isStreaming":false,"title":"Az éj felén túl (részlet)(nyitány)","artist":"Ákos","album":"A hét parancsszó","uri":"NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac","trackType":"flac"} Oct 13 22:22:33 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:33 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:33 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:33 volumio volumio[1056]: info: ------------------------------ 17ms Oct 13 22:22:33 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:22:33 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:33 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:33 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:33 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2098,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"203 Kbps","isStreaming":false,"title":"Az éj felén túl (részlet)(nyitány)","artist":"Ákos","album":"A hét parancsszó","uri":"NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac","trackType":"flac"} Oct 13 22:22:33 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:33 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:33 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:33 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:33 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:33 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:33 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:33 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:33 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:33 volumio volumio[1056]: info: ------------------------------ 19ms Oct 13 22:22:36 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:22:36 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:22:36 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:36 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia Oct 13 22:22:36 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia in service mpd Oct 13 22:22:36 volumio volumio[1056]: info: Oct 13 22:22:36 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:36 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAl%20di%20Meola_MCLaughlin_Paco_de_Lucia%2FCD%20Track%2001.wav&metadata=false Oct 13 22:22:36 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav Oct 13 22:22:36 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAl%20di%20Meola_MCLaughlin_Paco_de_Lucia%2FCD%20Track%2002.wav&metadata=false Oct 13 22:22:36 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 02.wav Oct 13 22:22:36 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAl%20di%20Meola_MCLaughlin_Paco_de_Lucia%2FCD%20Track%2003.wav&metadata=false Oct 13 22:22:36 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 03.wav Oct 13 22:22:36 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAl%20di%20Meola_MCLaughlin_Paco_de_Lucia%2FCD%20Track%2004.wav&metadata=false Oct 13 22:22:36 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 04.wav Oct 13 22:22:36 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAl%20di%20Meola_MCLaughlin_Paco_de_Lucia%2FCD%20Track%2005.wav&metadata=false Oct 13 22:22:36 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 05.wav Oct 13 22:22:36 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Renaud%20Garcia-Fons%2FQuartet%20Linea%20del%20Sur/Linea%20del%20Sur/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAl%20di%20Meola_MCLaughlin_Paco_de_Lucia%2FLinea%20del%20Sur.wav&metadata=false Oct 13 22:22:36 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/Linea del Sur.wav Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand stop took 32 milliseconds Oct 13 22:22:36 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand stop took 1 milliseconds Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:22:36 volumio volumio[1056]: info: Oct 13 22:22:36 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:36 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand clear took 0 milliseconds Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav" Oct 13 22:22:36 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: 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":"Az éj felén túl (részlet)(nyitány)","artist":"Ákos","album":"A hét parancsszó","uri":"NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac","trackType":"flac"} Oct 13 22:22:36 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:36 volumio volumio[1056]: info: No code Oct 13 22:22:36 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:36 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:36 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:36 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:36 volumio volumio[1056]: info: ------------------------------ 5ms Oct 13 22:22:36 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:22:36 volumio volumio[1056]: info: Oct 13 22:22:36 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:36 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:36 volumio volumio[1056]: info: ------------------------------ 4ms Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav" took 4 milliseconds Oct 13 22:22:36 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:22:36 volumio volumio[1056]: info: ------------------------------ 2ms Oct 13 22:22:36 volumio volumio[1056]: info: sendMpdCommand play took 3 milliseconds Oct 13 22:22:37 volumio volumio[1056]: info: Oct 13 22:22:37 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:37 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:37 volumio volumio[1056]: info: Oct 13 22:22:37 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:37 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:37 volumio volumio[1056]: info: sendMpdCommand status took 14 milliseconds Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:37 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:37 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:37 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:37 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:37 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:37 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1961,"duration":687,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"CD Track 01","artist":null,"album":null,"uri":"NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav","trackType":"wav"} Oct 13 22:22:37 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:37 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:37 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:37 volumio volumio[1056]: info: ------------------------------ 17ms Oct 13 22:22:37 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:22:37 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:37 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:37 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:37 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:37 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2098,"duration":687,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"CD Track 01","artist":null,"album":null,"uri":"NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav","trackType":"wav"} Oct 13 22:22:37 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:37 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:37 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:37 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:37 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:37 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:37 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:37 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:37 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:37 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:37 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:37 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:37 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:37 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:37 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:37 volumio volumio[1056]: info: ------------------------------ 5ms Oct 13 22:22:39 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:39 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:39 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:39 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:39 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:39 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:39 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:39 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:22:39 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:39 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:22:39 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:22:39 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:39 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:22:39 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:39 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:39 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:22:39 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:22:39 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:39 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Andrea Bocelli Oct 13 22:22:39 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Andrea Bocelli in service mpd Oct 13 22:22:40 volumio volumio[1056]: info: Oct 13 22:22:40 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%202007%20-%20Vivere%20-%20The%20Best%20of%2F2007%20-%20Vivere%20-%20The%20Best%20of%20(DTS%205.1%2024-48%2C%20FLAC%202.0%2016-44).mka&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%202011%20-%20Concerto%20-%20One%20Night%20in%20Central%20Park%2F2011%20-%20Concerto%20-%20One%20Night%20in%20Central%20Park%20(DTS%205.1%2024-48%2C%20FLAC%202.0%2016-44).mka&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2011 - Concerto - One Night in Central Park/2011 - Concerto - One Night in Central Park (DTS 5.1 24-48, FLAC 2.0 16-44).mka Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F01%20-%20You%20ll%20Never%20Walk%20Alone.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/01 - You ll Never Walk Alone.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F02%20-%20Fratello%20Sole%20Sorella%20Luna%20(Dolce%20%C3%A8%20Sentire).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/02 - Fratello Sole Sorella Luna (Dolce è Sentire).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F03%20-%20Hallelujah.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/03 - Hallelujah.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli%2C%20Cecilia%20Bartoli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F04%20-%20Pianissimo.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/04 - Pianissimo.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli%2C%20Alison%20Krauss/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F05%20-%20Amazing%20Grace%20(arr%20%20Mercurio).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/05 - Amazing Grace (arr Mercurio).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F06%20-%20Preghiera%20(arr%20%20Mercurio).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/06 - Preghiera (arr Mercurio).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Paolo%20Buonvino%2C%20Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F07%20-%20Gratia%20Plena%20(From%20%20%20Fatima%20%20%20Soundtrack).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/07 - Gratia Plena (From Fatima Soundtrack).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F08%20-%20Cantique%20de%20Jean%20Racine.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/08 - Cantique de Jean Racine.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F09%20-%20Inno%20Sussurato.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/09 - Inno Sussurato.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F10%20-%20Oh%2C%20Madre%20Benedetta%20%20(Adagio%20Di%20Albinoni).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/10 - Oh, Madre Benedetta (Adagio Di Albinoni).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli%2C%20Cecilia%20Bartoli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F11%20-%20I%20Believe.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/11 - I Believe.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F12%20-%20Ave%20Maria.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/12 - Ave Maria.flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F13%20-%20Angele%20Dei%20(arr%20%20Kaye).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/13 - Angele Dei (arr Kaye).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F14%20-%20Agnus%20Dei%20(Intermezzo%20from%20L%20arl%C3%A9sienne%20Suite%20No%202%2C%20No%206).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/14 - Agnus Dei (Intermezzo from L arlésienne Suite No 2, No 6).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F15%20-%20Mui%20Grandes%20Noit%E2%80%99%20E%20D%C3%ADa%20(No%20%2057%20from%20Cantigas%20de%20Santa%20Maria)%20%20arr%20%20Mercurio%20.flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/15 - Mui Grandes Noit’ E Día (No 57 from Cantigas de Santa Maria) arr Mercurio .flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F16%20-%20Mira%20Il%20Tuo%20Popolo%20(arr%20%20Mercurio).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/16 - Mira Il Tuo Popolo (arr Mercurio).flac Oct 13 22:22:40 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Andrea%20Bocelli/Believe%20(Deluxe)/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%20Believe%20(Deluxe)%20-%202020%20%5B24-96%5D%2F17%20-%20Amazing%20Grace%20(arr%20%20Mercurio).flac&metadata=false Oct 13 22:22:40 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - Believe (Deluxe) - 2020 [24-96]/17 - Amazing Grace (arr Mercurio).flac Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand stop took 390 milliseconds Oct 13 22:22:40 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand stop took 1 milliseconds Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:22:40 volumio volumio[1056]: info: Oct 13 22:22:40 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:40 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand clear took 1 milliseconds Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka" Oct 13 22:22:40 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:40 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:40 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: 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":"CD Track 01","artist":null,"album":null,"uri":"NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav","trackType":"wav"} Oct 13 22:22:40 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:40 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:40 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:40 volumio volumio[1056]: info: No code Oct 13 22:22:40 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:40 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:40 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:40 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:40 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:40 volumio volumio[1056]: info: ------------------------------ 7ms Oct 13 22:22:40 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:22:40 volumio volumio[1056]: info: Oct 13 22:22:40 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:40 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:40 volumio volumio[1056]: info: ------------------------------ 4ms Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka" took 3 milliseconds Oct 13 22:22:40 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:22:40 volumio volumio[1056]: info: ------------------------------ 2ms Oct 13 22:22:40 volumio volumio[1056]: info: sendMpdCommand play took 2 milliseconds Oct 13 22:22:42 volumio volumio[1056]: info: Oct 13 22:22:42 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:42 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:42 volumio volumio[1056]: info: Oct 13 22:22:42 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:42 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:42 volumio volumio[1056]: info: sendMpdCommand status took 17 milliseconds Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:42 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:22:42 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:42 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:42 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:42 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:42 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2612,"duration":4126,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1536 Kbps","isStreaming":false,"title":"DTS 5.1 1509 Kbps 24 bits 48 kHz","artist":null,"album":null,"uri":"NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka","trackType":"mka"} Oct 13 22:22:42 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:42 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:42 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:42 volumio volumio[1056]: info: ------------------------------ 19ms Oct 13 22:22:42 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:42 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:42 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:42 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:42 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:42 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2612,"duration":4126,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1536 Kbps","isStreaming":false,"title":"DTS 5.1 1509 Kbps 24 bits 48 kHz","artist":null,"album":null,"uri":"NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka","trackType":"mka"} Oct 13 22:22:42 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:42 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:42 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:42 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:42 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:42 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:42 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:42 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:42 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:42 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:42 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:42 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:42 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:42 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:42 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:42 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:22:48 volumio volumio[1056]: info: CoreCommandRouter::volumioSeek Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::seek Oct 13 22:22:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:48 volumio volumio[1056]: info: TRACKBLOCK {"uri":"mnt/NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka","service":"mpd","name":"DTS 5.1 1509 Kbps 24 bits 48 kHz","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=522&web=//extralarge&path=%2FNAS%2FJozene%2FAndrea%20Bocelli%2FAndrea%20Bocelli%20-%202007%20-%20Vivere%20-%20The%20Best%20of&metadata=false","duration":4126,"samplerate":48,"bitdepth":"32 bit","trackType":"mka","channels":6} Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:48 volumio volumio[1056]: info: ControllerMpd::seek Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:48 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:48 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:48 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:48 volumio volumio[1056]: error: null Oct 13 22:22:48 volumio volumio[1056]: info: Oct 13 22:22:48 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:48 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:48 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:22:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:48 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:48 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:48 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:48 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:48 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483000,"duration":4126,"samplerate":48,"bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"1536 Kbps","isStreaming":false,"title":"DTS 5.1 1509 Kbps 24 bits 48 kHz","artist":null,"album":null,"uri":"NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka","trackType":"mka"} Oct 13 22:22:48 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:48 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:48 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:48 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:48 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:48 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:48 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:48 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:22:52 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:22:52 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:22:52 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:52 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz Oct 13 22:22:52 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz in service mpd Oct 13 22:22:52 volumio volumio[1056]: info: Oct 13 22:22:52 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:52 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2F2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz%2F2L38_01_DXD.wav&metadata=false Oct 13 22:22:52 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L38_01_DXD.wav Oct 13 22:22:52 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2F2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz%2F2L49SACD_tr1_DXD_stereo.wav&metadata=false Oct 13 22:22:52 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L49SACD_tr1_DXD_stereo.wav Oct 13 22:22:52 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2F2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz%2F2L50SACD_tr1_DXD_stereo.wav&metadata=false Oct 13 22:22:52 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L50SACD_tr1_DXD_stereo.wav Oct 13 22:22:52 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2F2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz%2F2L53SACD_04_stereo_DXD.wav&metadata=false Oct 13 22:22:52 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L53SACD_04_stereo_DXD.wav Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand stop took 17 milliseconds Oct 13 22:22:52 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L38_01_DXD.wav Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand stop took 0 milliseconds Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:22:52 volumio volumio[1056]: info: Oct 13 22:22:52 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:52 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand clear took 0 milliseconds Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L38_01_DXD.wav" Oct 13 22:22:52 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: 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":"DTS 5.1 1509 Kbps 24 bits 48 kHz","artist":null,"album":null,"uri":"NAS/Jozene/Andrea Bocelli/Andrea Bocelli - 2007 - Vivere - The Best of/2007 - Vivere - The Best of (DTS 5.1 24-48, FLAC 2.0 16-44).mka","trackType":"mka"} Oct 13 22:22:52 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:52 volumio volumio[1056]: info: No code Oct 13 22:22:52 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:52 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:52 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:52 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:52 volumio volumio[1056]: info: ------------------------------ 6ms Oct 13 22:22:52 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:22:52 volumio volumio[1056]: info: Oct 13 22:22:52 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:52 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:52 volumio volumio[1056]: info: ------------------------------ 5ms Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L38_01_DXD.wav" took 5 milliseconds Oct 13 22:22:52 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:22:52 volumio volumio[1056]: info: ------------------------------ 2ms Oct 13 22:22:52 volumio volumio[1056]: info: sendMpdCommand play took 2 milliseconds Oct 13 22:22:54 volumio volumio[1056]: info: Oct 13 22:22:54 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:54 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:54 volumio volumio[1056]: info: Oct 13 22:22:54 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:54 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:54 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:54 volumio volumio[1056]: info: sendMpdCommand status took 2 milliseconds Oct 13 22:22:54 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:54 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:54 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:54 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:54 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1669,"duration":555,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"16934 Kbps","isStreaming":false,"title":"2L38_01_DXD.wav","artist":null,"album":null,"uri":"NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L38_01_DXD.wav","trackType":"wav"} Oct 13 22:22:54 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:54 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:54 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:54 volumio volumio[1056]: info: ------------------------------ 2ms Oct 13 22:22:54 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 13 22:22:54 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:54 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:54 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:54 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:54 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1669,"duration":555,"samplerate":"352.8 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"16934 Kbps","isStreaming":false,"title":"2L38_01_DXD.wav","artist":null,"album":null,"uri":"NAS/Jozene/2L.HiRes.Stereo.WAVs.DXD.24-bit.352.8kHz/2L38_01_DXD.wav","trackType":"wav"} Oct 13 22:22:54 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:54 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:54 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:54 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:54 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:54 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:54 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:54 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:54 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:54 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:54 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:54 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:54 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:54 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:54 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:54 volumio volumio[1056]: info: ------------------------------ 32ms Oct 13 22:22:56 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:56 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:56 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:22:56 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:22:56 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:56 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Akos - A het parancsszo Oct 13 22:22:56 volumio volumio[1056]: info: Using cached record of: music-library/NAS/Jozene/Akos - A het parancsszo Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:56 volumio volumio[1056]: info: Oct 13 22:22:56 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand stop took 4 milliseconds Oct 13 22:22:56 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand stop took 2 milliseconds Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:22:56 volumio volumio[1056]: info: Oct 13 22:22:56 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:56 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand clear took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac" Oct 13 22:22:56 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:22:56 volumio volumio[1056]: info: ------------------------------ 0ms Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac" took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:22:56 volumio volumio[1056]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 22:22:56 volumio volumio[1056]: info: ------------------------------ 0ms Oct 13 22:22:56 volumio volumio[1056]: info: Oct 13 22:22:56 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:56 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand play took 1 milliseconds Oct 13 22:22:56 volumio volumio[1056]: info: ------------------------------ 1ms Oct 13 22:22:56 volumio volumio[1056]: info: Oct 13 22:22:56 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:56 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:56 volumio volumio[1056]: info: Oct 13 22:22:56 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand status took 22 milliseconds Oct 13 22:22:56 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:56 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:56 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Az éj felén túl (részlet)(nyitány)","artist":"Ákos","album":"A hét parancsszó","uri":"NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac","trackType":"flac"} Oct 13 22:22:56 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:56 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"Az éj felén túl (részlet)(nyitány)","artist":"Ákos","album":"A hét parancsszó","uri":"NAS/Jozene/Akos - A het parancsszo/01 - Az éj felén túl (részlet)(nyitány).flac","trackType":"flac"} Oct 13 22:22:56 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:56 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:56 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:56 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:56 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:56 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:56 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:56 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:56 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:56 volumio volumio[1056]: info: ------------------------------ 24ms Oct 13 22:22:56 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:22:59 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:59 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:59 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:22:59 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:22:59 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:22:59 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia Oct 13 22:22:59 volumio volumio[1056]: info: Using cached record of: music-library/NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:22:59 volumio volumio[1056]: info: Oct 13 22:22:59 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand stop took 4 milliseconds Oct 13 22:22:59 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand stop took 2 milliseconds Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:22:59 volumio volumio[1056]: info: Oct 13 22:22:59 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:59 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand clear took 1 milliseconds Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav" Oct 13 22:22:59 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:22:59 volumio volumio[1056]: info: ------------------------------ 1ms Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:59 volumio volumio[1056]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 22:22:59 volumio volumio[1056]: info: ------------------------------ 1ms Oct 13 22:22:59 volumio volumio[1056]: info: Oct 13 22:22:59 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:22:59 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav" took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:22:59 volumio volumio[1056]: info: ------------------------------ 0ms Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand play took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: info: Oct 13 22:22:59 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:59 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:59 volumio volumio[1056]: info: Oct 13 22:22:59 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand status took 2 milliseconds Oct 13 22:22:59 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:59 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:22:59 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":687,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"CD Track 01","artist":null,"album":null,"uri":"NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav","trackType":"wav"} Oct 13 22:22:59 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:22:59 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1208,"duration":687,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"CD Track 01","artist":null,"album":null,"uri":"NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav","trackType":"wav"} Oct 13 22:22:59 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:22:59 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:59 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:59 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:59 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:22:59 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:22:59 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:22:59 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:22:59 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:22:59 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:22:59 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:01 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:23:01 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:23:01 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:01 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop Oct 13 22:23:01 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop in service mpd Oct 13 22:23:01 volumio volumio[1056]: info: Oct 13 22:23:01 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FA1%20Limehouse%20Blues.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FA2%20I'm%20Confessing.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A2 I'm Confessing.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FA3%20High%20Life.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A3 High Life.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FB1%20Struttin'%20With%20Some%20Barbeq.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/B1 Struttin' With Some Barbeq.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FB2%20Jeep's%20Blues.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/B2 Jeep's Blues.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FB3%20Stuffy.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/B3 Stuffy.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FC1%20Lady%20Be%20Good.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/C1 Lady Be Good.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FC2%20Here%20Is%20That%20Rainy%20Day.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/C2 Here Is That Rainy Day.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FC3%20Barbados.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/C3 Barbados.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FD1%20How%20High%20The%20Moon.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/D1 How High The Moon.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FD2%20Take%20Five.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/D2 Take Five.flac Oct 13 22:23:01 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=//extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%2FD3%20Everything%20Happens%20To%20Me.flac&metadata=false Oct 13 22:23:01 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/D3 Everything Happens To Me.flac Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand stop took 57 milliseconds Oct 13 22:23:01 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand stop took 0 milliseconds Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:23:01 volumio volumio[1056]: info: Oct 13 22:23:01 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:01 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand clear took 1 milliseconds Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac" Oct 13 22:23:01 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: 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":"CD Track 01","artist":null,"album":null,"uri":"NAS/Jozene/Al di Meola_MCLaughlin_Paco_de_Lucia/CD Track 01.wav","trackType":"wav"} Oct 13 22:23:01 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:01 volumio volumio[1056]: info: No code Oct 13 22:23:01 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:01 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:01 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:01 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:01 volumio volumio[1056]: info: ------------------------------ 6ms Oct 13 22:23:01 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:23:01 volumio volumio[1056]: info: Oct 13 22:23:01 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:01 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:01 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac" took 3 milliseconds Oct 13 22:23:01 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:23:01 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:01 volumio volumio[1056]: info: sendMpdCommand play took 3 milliseconds Oct 13 22:23:02 volumio volumio[1056]: info: Oct 13 22:23:02 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:02 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:02 volumio volumio[1056]: info: Oct 13 22:23:02 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:02 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:02 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:02 volumio volumio[1056]: info: sendMpdCommand status took 37 milliseconds Oct 13 22:23:02 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:02 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:02 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:02 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:02 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2116,"duration":575,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2835 Kbps","isStreaming":false,"title":"A1 Limehouse Blues.flac","artist":null,"album":null,"uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac","trackType":"flac"} Oct 13 22:23:02 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:02 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:02 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:02 volumio volumio[1056]: info: ------------------------------ 38ms Oct 13 22:23:02 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:02 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:02 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:02 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:02 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:02 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2116,"duration":575,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2835 Kbps","isStreaming":false,"title":"A1 Limehouse Blues.flac","artist":null,"album":null,"uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac","trackType":"flac"} Oct 13 22:23:02 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:02 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:02 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:23:02 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:23:02 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:02 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:02 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:02 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:02 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:02 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:02 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:02 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:02 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:02 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:02 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:02 volumio volumio[1056]: info: ------------------------------ 39ms Oct 13 22:23:05 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:23:05 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:23:05 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:05 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128] Oct 13 22:23:05 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128] in service mpd Oct 13 22:23:05 volumio volumio[1056]: info: Oct 13 22:23:05 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F01-The%20Wizard%20of%20Oz%20(inst)_%20Over%20The%20Rainbow.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F02-Gubben%20och%20Kallingen.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/02-Gubben och Kallingen.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F03-In%20a%20Mellotone_%20In%20A%20Mellow%20Tone.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/03-In a Mellotone_ In A Mellow Tone.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F04-Nancy%20(with%20the%20Laughing%20Face)_%20Nancy%20With%20The%20Laughing%20Face.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/04-Nancy (with the Laughing Face)_ Nancy With The Laughing Face.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F05-High%20Life%20(Take%202).dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/05-High Life (Take 2).dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F06-The%20Big%20Show_%20Poor%20Butterfly.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/06-The Big Show_ Poor Butterfly.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F07-Exactly%20Like%20You.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/07-Exactly Like You.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F08-Things%20Ain't%20What%20They%20Used%20To%20Be.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/08-Things Ain't What They Used To Be.dsf Oct 13 22:23:05 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D%2F09-It%20Don't%20Mean%20A%20Thing.dsf&metadata=false Oct 13 22:23:05 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/09-It Don't Mean A Thing.dsf Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand stop took 164 milliseconds Oct 13 22:23:05 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand stop took 0 milliseconds Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:23:05 volumio volumio[1056]: info: Oct 13 22:23:05 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:05 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand clear took 0 milliseconds Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf" Oct 13 22:23:05 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: 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":"A1 Limehouse Blues.flac","artist":null,"album":null,"uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop/A1 Limehouse Blues.flac","trackType":"flac"} Oct 13 22:23:05 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:05 volumio volumio[1056]: info: No code Oct 13 22:23:05 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:05 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:05 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:05 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:05 volumio volumio[1056]: info: ------------------------------ 6ms Oct 13 22:23:05 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:23:05 volumio volumio[1056]: info: Oct 13 22:23:05 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:05 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:05 volumio volumio[1056]: info: ------------------------------ 4ms Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf" took 3 milliseconds Oct 13 22:23:05 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:23:05 volumio volumio[1056]: info: ------------------------------ 2ms Oct 13 22:23:05 volumio volumio[1056]: info: sendMpdCommand play took 3 milliseconds Oct 13 22:23:06 volumio volumio[1056]: info: Oct 13 22:23:06 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:06 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:06 volumio volumio[1056]: info: Oct 13 22:23:06 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:06 volumio volumio[1056]: info: sendMpdCommand status took 22 milliseconds Oct 13 22:23:06 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:06 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:06 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:06 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:06 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:06 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:06 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":429,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","trackType":"dsf"} Oct 13 22:23:06 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:06 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:06 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:06 volumio volumio[1056]: info: ------------------------------ 23ms Oct 13 22:23:06 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:06 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:06 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:06 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:06 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:06 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":429,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","trackType":"dsf"} Oct 13 22:23:06 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:06 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:06 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:23:06 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:23:06 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:06 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:06 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:06 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:06 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:06 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:06 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:06 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:06 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:06 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:06 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:06 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:13 volumio volumio[1056]: info: CoreCommandRouter::volumioSeek Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::seek Oct 13 22:23:13 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:13 volumio volumio[1056]: info: TRACKBLOCK {"uri":"mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","service":"mpd","name":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=522&web=Arne%20Domnerus/Jazz%20At%20The%20Pawnshop%202/extralarge&path=%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%202%20%5BDSD128%5D&metadata=false","duration":429,"samplerate":"5.64 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:23:13 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:13 volumio volumio[1056]: info: ControllerMpd::seek Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:13 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:13 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:13 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:13 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:13 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:13 volumio volumio[1056]: error: null Oct 13 22:23:13 volumio volumio[1056]: info: Oct 13 22:23:13 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:13 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:13 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:13 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:23:13 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:13 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:13 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:13 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:13 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:13 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:13 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:13 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44000,"duration":429,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","trackType":"dsf"} Oct 13 22:23:13 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:23:13 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:13 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:13 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:13 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:13 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:13 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:13 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:13 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:13 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:13 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:13 volumio volumio[1056]: info: ------------------------------ 5ms Oct 13 22:23:32 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:32 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:32 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:32 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:32 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:23:32 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:32 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:23:32 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:23:32 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:32 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:23:32 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:32 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:32 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:23:32 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:23:32 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:32 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128] Oct 13 22:23:32 volumio volumio[1056]: info: Using cached record of: music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128] Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:33 volumio volumio[1056]: info: Oct 13 22:23:33 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand stop took 4 milliseconds Oct 13 22:23:33 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand stop took 1 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:23:33 volumio volumio[1056]: info: Oct 13 22:23:33 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:33 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand clear took 0 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf" Oct 13 22:23:33 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:23:33 volumio volumio[1056]: info: ------------------------------ 1ms Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:33 volumio volumio[1056]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 13 22:23:33 volumio volumio[1056]: info: ------------------------------ 1ms Oct 13 22:23:33 volumio volumio[1056]: info: Oct 13 22:23:33 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:33 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf" took 1 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:23:33 volumio volumio[1056]: info: ------------------------------ 0ms Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand play took 0 milliseconds Oct 13 22:23:33 volumio volumio[1056]: info: Oct 13 22:23:33 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:33 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:33 volumio volumio[1056]: info: Oct 13 22:23:33 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand status took 2 milliseconds Oct 13 22:23:33 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:33 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":429,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","trackType":"dsf"} Oct 13 22:23:33 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:33 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:33 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:23:33 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:33 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":429,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","trackType":"dsf"} Oct 13 22:23:33 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:23:33 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:33 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:33 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:33 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:33 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:33 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:35 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:23:35 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:35 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128] Oct 13 22:23:35 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128] in service mpd Oct 13 22:23:35 volumio volumio[1056]: info: Oct 13 22:23:35 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:35 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domn%C3%A9rus%2C%20Bengt%20Hallberg%2C%20Georg%20Riedel%2C%20Egil%20Johansen%2C%20Lars%20Erstrand/Jazz%20At%20The%20Pawnshop%2C%20Vol.%203%3A%20Good%20Vibes/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%203.%20Good%20Vibes%20(1976-2017)%20%5BDSD128%5D%2F01-Now's%20the%20Time.dsf&metadata=false Oct 13 22:23:35 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/01-Now's the Time.dsf Oct 13 22:23:35 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domn%C3%A9rus%2C%20Bengt%20Hallberg%2C%20Georg%20Riedel%2C%20Egil%20Johansen%2C%20Lars%20Erstrand/Jazz%20At%20The%20Pawnshop%2C%20Vol.%203%3A%20Good%20Vibes/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%203.%20Good%20Vibes%20(1976-2017)%20%5BDSD128%5D%2F02-Out%20of%20Nowhere.dsf&metadata=false Oct 13 22:23:35 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/02-Out of Nowhere.dsf Oct 13 22:23:35 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domn%C3%A9rus%2C%20Bengt%20Hallberg%2C%20Georg%20Riedel%2C%20Egil%20Johansen%2C%20Lars%20Erstrand/Jazz%20At%20The%20Pawnshop%2C%20Vol.%203%3A%20Good%20Vibes/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%203.%20Good%20Vibes%20(1976-2017)%20%5BDSD128%5D%2F03-In%20a%20Mellotone_%20In%20a%20Mellow%20Tone.dsf&metadata=false Oct 13 22:23:35 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/03-In a Mellotone_ In a Mellow Tone.dsf Oct 13 22:23:35 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domn%C3%A9rus%2C%20Bengt%20Hallberg%2C%20Georg%20Riedel%2C%20Egil%20Johansen%2C%20Lars%20Erstrand/Jazz%20At%20The%20Pawnshop%2C%20Vol.%203%3A%20Good%20Vibes/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%203.%20Good%20Vibes%20(1976-2017)%20%5BDSD128%5D%2F04-Take%20the%20A%20Train.dsf&metadata=false Oct 13 22:23:35 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/04-Take the A Train.dsf Oct 13 22:23:35 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domn%C3%A9rus%2C%20Bengt%20Hallberg%2C%20Georg%20Riedel%2C%20Egil%20Johansen%2C%20Lars%20Erstrand/Jazz%20At%20The%20Pawnshop%2C%20Vol.%203%3A%20Good%20Vibes/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%203.%20Good%20Vibes%20(1976-2017)%20%5BDSD128%5D%2F05-Going%20Places_%20Jeepers%20Creepers_%20Jeepers%20Creepers.dsf&metadata=false Oct 13 22:23:35 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/05-Going Places_ Jeepers Creepers_ Jeepers Creepers.dsf Oct 13 22:23:35 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Arne%20Domn%C3%A9rus%2C%20Bengt%20Hallberg%2C%20Georg%20Riedel%2C%20Egil%20Johansen%2C%20Lars%20Erstrand/Jazz%20At%20The%20Pawnshop%2C%20Vol.%203%3A%20Good%20Vibes/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FArne%20Domnerus%20-%20Jazz%20At%20The%20Pawnshop%203.%20Good%20Vibes%20(1976-2017)%20%5BDSD128%5D%2F06-Struttin'%20with%20some%20Barbeque.dsf&metadata=false Oct 13 22:23:35 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/06-Struttin' with some Barbeque.dsf Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand stop took 156 milliseconds Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPlay Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::play index 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::play index undefined Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::clearAddPlayTracks NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/01-Now's the Time.dsf Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand status took 2 milliseconds Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand stop took 0 milliseconds Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 22:23:35 volumio volumio[1056]: info: Oct 13 22:23:35 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:35 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand clear took 2 milliseconds Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/01-Now's the Time.dsf" Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: 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":"The Wizard of Oz (inst): Over The Rainbow","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 2 [DSD128]/01-The Wizard of Oz (inst)_ Over The Rainbow.dsf","trackType":"dsf"} Oct 13 22:23:35 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:35 volumio volumio[1056]: info: No code Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:35 volumio volumio[1056]: info: ------------------------------ 8ms Oct 13 22:23:35 volumio volumio[1056]: error: updateQueue error: null Oct 13 22:23:35 volumio volumio[1056]: info: Oct 13 22:23:35 volumio volumio[1056]: ---------------------------- MPD announces system playlist update Oct 13 22:23:35 volumio volumio[1056]: info: Ignoring MPD Status Update Oct 13 22:23:35 volumio volumio[1056]: info: ------------------------------ 5ms Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand add "NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/01-Now's the Time.dsf" took 4 milliseconds Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Oct 13 22:23:35 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand play took 3 milliseconds Oct 13 22:23:35 volumio volumio[1056]: info: Oct 13 22:23:35 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:35 volumio volumio[1056]: info: Oct 13 22:23:35 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand status took 1 milliseconds Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::getState Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand status took 0 milliseconds Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::parseState Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":572,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Now's the Time","artist":"Arne Domnérus, Bengt Hallberg, Georg Riedel, Egil Johansen, Lars Erstrand","album":"Jazz At The Pawnshop, Vol. 3: Good Vibes","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/01-Now's the Time.dsf","trackType":"dsf"} Oct 13 22:23:35 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Oct 13 22:23:35 volumio volumio[1056]: info: ------------------------------ 1ms Oct 13 22:23:35 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 22:23:35 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 22:23:35 volumio volumio[1056]: info: ControllerMpd::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":572,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Now's the Time","artist":"Arne Domnérus, Bengt Hallberg, Georg Riedel, Egil Johansen, Lars Erstrand","album":"Jazz At The Pawnshop, Vol. 3: Good Vibes","uri":"NAS/Jozene/Arne Domnerus - Jazz At The Pawnshop 3. Good Vibes (1976-2017) [DSD128]/01-Now's the Time.dsf","trackType":"dsf"} Oct 13 22:23:35 volumio volumio[1056]: verbose: CURRENT POSITION 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::syncState stateService play Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus play Oct 13 22:23:35 volumio volumio[1056]: info: Received an update from plugin. extracting info from payload Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:35 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:35 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:35 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:35 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:35 volumio volumio[1056]: info: ------------------------------ 3ms Oct 13 22:23:37 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:37 volumio volumio[1056]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::ClearQueue Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::stop Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 22:23:37 volumio volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::pushState Oct 13 22:23:37 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:37 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 22:23:37 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 22:23:37 volumio volumio[1056]: info: MRS: Pushing multiroomSync output update for this device Oct 13 22:23:37 volumio volumio[1056]: info: MRS: Pushing multiroomSync output Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 22:23:37 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Oct 13 22:23:37 volumio volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 22:23:37 volumio volumio[1056]: info: ControllerMpd::stop Oct 13 22:23:37 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 22:23:37 volumio volumio[1056]: info: CorePlayQueue::clearPlayQueue Oct 13 22:23:37 volumio volumio[1056]: info: CorePlayQueue::saveQueue Oct 13 22:23:37 volumio volumio[1056]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:23:37 volumio volumio[1056]: info: CoreStateMachine::addQueueItems Oct 13 22:23:37 volumio volumio[1056]: info: CorePlayQueue::addQueueItems Oct 13 22:23:37 volumio volumio[1056]: info: Preload queue cleared Oct 13 22:23:37 volumio volumio[1056]: info: Adding Item to queue: music-library/NAS/Jozene/Bach C.Ph.E - Cello Concertos - Liszt Ferenc Chamber Orchestra, Budapest Oct 13 22:23:37 volumio volumio[1056]: info: Exploding uri music-library/NAS/Jozene/Bach C.Ph.E - Cello Concertos - Liszt Ferenc Chamber Orchestra, Budapest in service mpd Oct 13 22:23:38 volumio volumio[1056]: info: Oct 13 22:23:38 volumio volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 22:23:38 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Liszt%20Ferenc%20Chamber%20Orchestra%2C%20Budapest%2C%20Janos%20Rolla/Carl%20Philipp%20Emanuel%20Bach%20-%20Cello%20Concertos/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FBach%20C.Ph.E%20-%20Cello%20Concertos%20-%20Liszt%20Ferenc%20Chamber%20Orchestra%2C%20Budapest%2FLPImage.cue&metadata=false Oct 13 22:23:38 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Bach C.Ph.E - Cello Concertos - Liszt Ferenc Chamber Orchestra, Budapest/LPImage.cue Oct 13 22:23:38 volumio volumio[1056]: info: ALBUMART /albumart?cacheid=522&web=Liszt%20Ferenc%20Chamber%20Orchestra%2C%20Budapest%2C%20Janos%20Rolla/Carl%20Philipp%20Emanuel%20Bach%20-%20Cello%20Concertos/extralarge&path=%2Fmnt%2FNAS%2FJozene%2FBach%20C.Ph.E%20-%20Cello%20Concertos%20-%20Liszt%20Ferenc%20Chamber%20Orchestra%2C%20Budapest%2FLPImage.cue&metadata=false Oct 13 22:23:38 volumio volumio[1056]: info: URI /mnt/NAS/Jozene/Bach C.Ph.E - Cello Concertos - Liszt Ferenc Chamber Orchestra, Budapest/LPImage.cue Oct 13 22:23:38 volumio volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 22:23:38 volumio volumio[1056]: Error: Unable to resolve or reject the same promise twice Oct 13 22:23:38 volumio volumio[1056]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 13 22:23:38 volumio volumio[1056]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 13 22:23:38 volumio volumio[1056]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 13 22:23:38 volumio volumio[1056]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 13 22:23:38 volumio volumio[1056]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 13 22:23:38 volumio volumio[1056]: at Socket.emit (node:events:514:28) Oct 13 22:23:38 volumio volumio[1056]: at addChunk (node:internal/streams/readable:343:12) Oct 13 22:23:38 volumio volumio[1056]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 13 22:23:38 volumio volumio[1056]: at Readable.push (node:internal/streams/readable:253:10) Oct 13 22:23:38 volumio volumio[1056]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 13 22:23:38 volumio volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 22:23:38 volumio sudo[10581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 22:22' Oct 13 22:23:38 volumio sudo[10581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"